[eErrorOutput] PIPE_BUF: 4096 [eErrorOutput] F_SETPIPE_SZ: 1048576 [eErrorOutput] F_GETPIPE_SZ 0: 1048576 PYTHONPATH: /usr/lib/enigma2/python DVB_API_VERSION 5 DVB_API_VERSION_MINOR 10 13:52:19.0300 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.0312 [ ] base/e2avahi.cpp:313 avahi_watch_new [Avahi] avahi_watch_new(7 0x1) 13:52:19.0316 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:19.0333 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.0353 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.0361 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.0369 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.0372 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.0379 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.0384 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.0396 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.0398 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.0420 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.0425 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.0437 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.0439 [ ] base/e2avahi.cpp:279 avahi_client_callback [Avahi] client state: 2 13:52:19.0461 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.0489 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.0492 [ ] base/init.cpp:47 setRunlevel + (1) Background File Eraser 13:52:19.0495 [ ] base/init.cpp:47 setRunlevel + (5) Tuxtxt 13:52:19.0497 [ ] base/init.cpp:47 setRunlevel + (8) graphics acceleration manager 13:52:19.0502 [ ] base/init.cpp:47 setRunlevel + (9) Font Render Class 13:52:19.0503 [ ] gdi/font.cpp:190 fontRenderClass [FONT] initializing lib... 13:52:19.0613 [ ] gdi/font.cpp:198 fontRenderClass [FONT] loading fonts... 13:52:19.0616 [ ] gdi/font.cpp:203 fontRenderClass [FONT] Intializing font cache, using max. 4MB... 13:52:19.0627 [ ] base/init.cpp:47 setRunlevel + (9) gLCDDC 13:52:19.0629 [ E ] gdi/fblcd.cpp:37 eFbLCD [eFbLCD] /dev/fb1 No such file or directory 13:52:19.0630 [ E ] gdi/fblcd.cpp:77 eFbLCD [eFbLCD] framebuffer /dev/fb1 not available 13:52:19.0632 [ ] gdi/lcd.cpp:135 eDBoxLCD [eLCD] found OLED display! 13:52:19.0633 [ ] gdi/lcd.cpp:176 eDBoxLCD [eDboxLCD] xres=132, yres=64, bpp=8 lcd_type=1 13:52:19.0634 [ ] gdi/lcd.cpp:41 setSize [eLCD] (132x64x8) buffer 0x7b2a00 8448 bytes, stride 132, boxtype: formuler1 13:52:19.0636 [ ] gdi/glcddc.cpp:43 gLCDDC LCD resolution: 132 x 64 x 8 (stride: 132) 13:52:19.0637 [ ] base/init.cpp:47 setRunlevel + (9) GFBDC 13:52:19.0638 [ ] gdi/fb.cpp:83 fbClass [fb] /dev/fb0: 24300k video mem 13:52:19.0638 [ ] gdi/fb.cpp:167 fbClass [fb] 24300k video mem 13:52:19.0681 [ ] gdi/fb.cpp:268 SetMode [fb] double buffering available! 13:52:19.0683 [ ] gdi/fb.cpp:271 SetMode [fb] 2 page(s) available! 13:52:19.0884 [ ] gdi/gfbdc.cpp:310 setResolution [gFBDC] resolution: 1280 x 720 x 32 (stride: 5120) pages: 2 13:52:19.0887 [ ] gdi/gfbdc.cpp:314 setResolution [gFBDC] 17100kB available for acceleration surfaces. 13:52:19.0889 [ ] base/init.cpp:47 setRunlevel + (10) gRC 13:52:19.0891 [ ] gdi/grc.cpp:42 gRC RC thread created successfully 13:52:19.0892 [ ] base/init.cpp:47 setRunlevel + (15) eWindowStyleManager 13:52:19.0893 [ ] base/init.cpp:47 setRunlevel + (20) AVSwitch Driver 13:52:19.0894 [ ] driver/avswitch.cpp:24 eAVSwitch couldnt open /dev/dbox/fp0 to monitor vcr scart slow blanking changed! 13:52:19.0895 [ ] base/init.cpp:47 setRunlevel + (20) misc options 13:52:19.0896 [ ] base/init.cpp:47 setRunlevel + (20) RC Input layer 13:52:19.0897 [ ] base/init.cpp:47 setRunlevel + (20) UHF Modulator 13:52:19.0897 [ ] driver/rfmod.cpp:27 eRFmod couldnt open /dev/rfmod0!!!! 13:52:19.0898 [ ] base/init.cpp:47 setRunlevel + (20) DVB-CI UI 13:52:19.0898 [ ] base/init.cpp:47 setRunlevel + (21) input device driver 13:52:19.0901 [ ] driver/rc.cpp:131 getDeviceName [eRCInputEventDriver] devicename=dreambox remote control (native) 13:52:19.0903 [ ] driver/rcinput.cpp:639 eRCDeviceInputDev Input device "dreambox remote control (native)" is a remotecontrol 13:52:19.0905 [ ] driver/rc.cpp:131 getDeviceName [eRCInputEventDriver] devicename=dreambox advanced remote control (native) 13:52:19.0906 [ ] driver/rcinput.cpp:639 eRCDeviceInputDev Input device "dreambox advanced remote control (native)" is a remotecontrol 13:52:19.0909 [ ] driver/rc.cpp:131 getDeviceName [eRCInputEventDriver] devicename=front panel 13:52:19.0910 [ ] driver/rcinput.cpp:639 eRCDeviceInputDev Input device "front panel" is a remotecontrol 13:52:19.0911 [ ] driver/rc.cpp:131 getDeviceName [eRCInputEventDriver] devicename=RC for hdmi_cec 13:52:19.0911 [ ] driver/rcinput.cpp:639 eRCDeviceInputDev Input device "RC for hdmi_cec" is a remotecontrol 13:52:19.0912 [ ] driver/rcinput.cpp:696 eInputDeviceInit Found 4 input devices. 13:52:19.0913 [ ] base/init.cpp:47 setRunlevel + (22) Hdmi CEC driver 13:52:19.3858 [ ] base/init.cpp:47 setRunlevel + (30) eActionMap 13:52:19.3859 [ ] base/init.cpp:47 setRunlevel + (35) CA handler 13:52:19.3863 [ ] base/init.cpp:47 setRunlevel + (35) CI Slots 13:52:19.3864 [ ] dvb_ci/dvbci.cpp:269 eDVBCIInterfaces scanning for common interfaces.. 13:52:19.3869 [ ] dvb_ci/dvbci.cpp:2009 setSource CI Slot 0 setSource(0) 13:52:19.3871 [ ] dvb_ci/dvbci.cpp:1065 setInputSource eDVBCIInterfaces->setInputSource(0, 0) 13:52:19.3873 [ ] dvb_ci/dvbci.cpp:1065 setInputSource eDVBCIInterfaces->setInputSource(1, 1) 13:52:19.3874 [ ] dvb_ci/dvbci.cpp:308 eDVBCIInterfaces done, found 1 common interface slots 13:52:19.3876 [ ] base/init.cpp:47 setRunlevel + (40) eServiceCenter 13:52:19.3876 [ ] service/service.cpp:116 eServiceCenter settings instance. 13:52:19.3877 [ ] base/init.cpp:47 setRunlevel + (41) Stream server 13:52:19.3881 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.3895 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.3907 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.3920 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.3924 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.3940 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.3953 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.3987 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.3990 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:19.4007 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:19.4009 [ ] base/e2avahi.cpp:151 avahi_service_try_register [Avahi] Registered formuler1 (_e2stream._tcp) on formuler1:8001 13:52:19.4010 [ ] base/init.cpp:47 setRunlevel + (41) Encoders 13:52:19.4011 [ E ] base/cfile.h:17 CFile error /proc/stb/encoder/0/decoder [No such file or directory] 13:52:19.4012 [ ] base/init.cpp:47 setRunlevel + (41) eServiceFactoryDVB 13:52:19.4013 [ ] base/init.cpp:47 setRunlevel + (41) eServiceFactoryFS 13:52:19.4014 [ ] base/init.cpp:47 setRunlevel + (41) eServiceFactoryMP3 13:52:19.4025 [ ] base/init.cpp:47 setRunlevel + (41) eServiceFactoryM2TS 13:52:19.4026 [ ] base/init.cpp:47 setRunlevel + (41) eServiceFactoryHDMI 13:52:19.4026 [ ] base/init.cpp:47 setRunlevel + (41) eServiceFactoryTS 13:52:19.4036 [ ] base/init.cpp:47 setRunlevel + (41) eServiceFactoryWebTS 13:52:19.4049 [ ] base/init.cpp:47 setRunlevel + (41) eServiceFactoryDVD 13:52:19.4058 [ ] base/init.cpp:63 setRunlevel reached rl 70 13:52:19.4079 [ ] dvb/db.cpp:650 loadServicelist [eDVBDB] ---- opening lame channel db 13:52:19.4081 [ ] dvb/db.cpp:664 loadServicelist [eDVBDB] reading services (version 4) 13:52:19.4493 [ ] dvb/db.cpp:736 loadServicelist [eDVBDB] loaded 103 channels/transponders and 1444 services 13:52:19.4496 [ ] dvb/sec.cpp:1395 clear eDVBSatelliteEquipmentControl::clear() 13:52:19.4498 [ ] dvb/dvb.cpp:230 scanDevices scanning for frontends.. 13:52:19.4502 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/0/active_antenna_power [No such file or directory] 13:52:19.4503 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/0/active_antenna_power [No such file or directory] 13:52:19.4508 [ ] dvb/frontend.cpp:718 openFrontend m_need_delivery_system_workaround = 0 13:52:19.4509 [ ] dvb/frontend.cpp:720 openFrontend opening frontend 0 13:52:19.4513 [ ] dvb/frontend.cpp:3615 setDeliverySystem frontend 0 setDeliverySystem 17 13:52:19.4514 [ ] dvb/frontend.cpp:3615 setDeliverySystem frontend 0 setDeliverySystem 6 13:52:19.4516 [ ] dvb/frontend.cpp:3615 setDeliverySystem frontend 0 setDeliverySystem 5 13:52:19.4518 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/0/active_antenna_power [No such file or directory] 13:52:19.4519 [ ] dvb/frontend.cpp:905 closeFrontend try to close frontend 0 13:52:19.4520 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/0/active_antenna_power [No such file or directory] 13:52:19.4526 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/1/active_antenna_power [No such file or directory] 13:52:19.4527 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/1/active_antenna_power [No such file or directory] 13:52:19.4530 [ ] dvb/frontend.cpp:718 openFrontend m_need_delivery_system_workaround = 0 13:52:19.4531 [ ] dvb/frontend.cpp:720 openFrontend opening frontend 1 13:52:19.4537 [ ] dvb/frontend.cpp:3615 setDeliverySystem frontend 1 setDeliverySystem 17 13:52:19.4539 [ ] dvb/frontend.cpp:3615 setDeliverySystem frontend 1 setDeliverySystem 6 13:52:19.4539 [ ] dvb/frontend.cpp:3615 setDeliverySystem frontend 1 setDeliverySystem 5 13:52:19.4541 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/1/active_antenna_power [No such file or directory] 13:52:19.4542 [ ] dvb/frontend.cpp:905 closeFrontend try to close frontend 1 13:52:19.4543 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/1/active_antenna_power [No such file or directory] 13:52:19.4550 [ ] dvb/dvb.cpp:192 eDVBResourceManager boxtype detection via /proc/stb/info not possible... use fallback via demux count! 13:52:19.4551 [ ] dvb/dvb.cpp:202 eDVBResourceManager found 1 adapter, 2 frontends(2 sim) and 8 demux, boxtype 4 13:52:19.4567 [ ] dvb/dvbtime.cpp:263 eDVBLocalTimeHandler Use valid Linux Time :) (RTC?) 13:52:19.4589 [ ] dvb/epgcache.cpp:383 eEPGCache [EPGC] Initialized EPGCache (wait for setCacheFile call now) 13:52:19.4686 [ ] enigma.cpp:328 main Loading spinners... 13:52:19.5478 [ E ] base/cfile.h:17 CFile error /usr/share/enigma2/spinner/wait31.png [No such file or directory] 13:52:19.5479 [ ] gdi/epng.cpp:24 loadPNG [ePNG] couldn't open /usr/share/enigma2/spinner/wait31.png 13:52:19.5480 [ ] enigma.cpp:347 main found 30 spinner! 13:52:19.5483 [ ] base/ioprio.cpp:91 setIoPrio setIoPrio best-effort level 3 ok 13:52:20.2477 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/0/lnb_sense [No such file or directory] 13:52:20.2499 [ ] driver/misc_options.cpp:48 detected_12V_output couldn't open /proc/stb/misc/12V_output 13:52:20.2823 { } Components/Harddisk.py:810 enumerateBlockDevices [Harddisk] enumerating block devices... 13:52:20.3285 { } Components/Harddisk.py:115 __init__ [Harddisk] new device sda -> /dev/sda -> /dev/sda 13:52:20.3568 { } Components/Harddisk.py:819 enumerateNetworkMounts [Harddisk] enumerating network mounts... 13:52:20.4606 { } skin.py:189 [SKIN] loading user defined colors for skin XionHDF/skin_user_colors.xml 13:52:20.4611 { } skin.py:195 [SKIN] loading user defined header file for skin XionHDF/skin_user_header.xml 13:52:20.8221 { } skin.py:375 cachemenu menu_mainmenu 13:52:21.0894 [ ] gdi/grc.cpp:193 thread main thread is non-idle! display spinner! 13:52:21.3524 { } Components/Renderer/Picon.py:30 onMountpointAdded [Picon] adding path: /usr/share/enigma2/picon/ 13:52:21.4906 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:21.4909 { } Components/NimManager.py:951 readTransponders Reading satellites.xml 13:52:22.0358 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:22.0539 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:22.0544 { } Components/NimManager.py:758 canBeCompatible 1 is NOT multitype 13:52:22.0717 { } Components/NimManager.py:758 canBeCompatible 1 is NOT multitype 13:52:22.0721 [ ] dvb/sec.cpp:1395 clear eDVBSatelliteEquipmentControl::clear() 13:52:22.0723 { } Components/NimManager.py:164 update sec config cleared 13:52:22.0732 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:22.0740 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:22.0746 { } Components/NimManager.py:758 canBeCompatible 1 is NOT multitype 13:52:22.0751 { } Components/NimManager.py:758 canBeCompatible 1 is NOT multitype 13:52:22.0755 [ ] dvb/frontend.cpp:3652 setSlotInfo setSlotInfo for dvb frontend 0 to slotid 0, descr BCM7346 (internal), need rotorworkaround No, enabled Yes, DVB-S2 Yes 13:52:22.0756 [ ] dvb/frontend.cpp:3652 setSlotInfo setSlotInfo for dvb frontend 1 to slotid 1, descr BCM7346 (internal), need rotorworkaround No, enabled Yes, DVB-S2 Yes 13:52:22.0760 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:22.0766 { } Components/NimManager.py:758 canBeCompatible 1 is NOT multitype 13:52:22.0772 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:22.0776 { } Components/NimManager.py:232 update slot: 0 configmode: simple 13:52:22.0778 [ ] dvb/sec.cpp:1948 setSlotNotLinked eDVBSatelliteEquipmentControl::setSlotNotLinked(0) 13:52:22.0783 { } Components/NimManager.py:240 update diseqcmode: single 13:52:22.0787 [ ] dvb/sec.cpp:1447 addLNB eDVBSatelliteEquipmentControl::addLNB(0) 13:52:22.0798 [ ] dvb/sec.cpp:1704 setLNBSatCR eDVBSatelliteEquipmentControl::setLNBSatCR(-1) 13:52:22.0800 [ ] dvb/sec.cpp:1525 setLNBSatCRTuningAlgo eDVBSatelliteEquipmentControl::setLNBSatCRTuningAlgo(0) 13:52:22.0801 [ ] dvb/sec.cpp:1537 setLNBBootupTime eDVBSatelliteEquipmentControl::setLNBBootupTime(0) 13:52:22.0801 [ ] dvb/sec.cpp:1513 setLNBSatCRpositionnumber eDVBSatelliteEquipmentControl::setLNBSatCRpositionnumber(1) 13:52:22.0802 [ ] dvb/sec.cpp:1463 setLNBLOFL eDVBSatelliteEquipmentControl::setLNBLOFL(9750000) 13:52:22.0803 [ ] dvb/sec.cpp:1473 setLNBLOFH eDVBSatelliteEquipmentControl::setLNBLOFH(10600000) 13:52:22.0804 [ ] dvb/sec.cpp:1483 setLNBThreshold eDVBSatelliteEquipmentControl::setLNBThreshold(11700000) 13:52:22.0806 [ ] dvb/sec.cpp:1493 setLNBIncreasedVoltage eDVBSatelliteEquipmentControl::setLNBIncreasedVoltage(0) 13:52:22.0807 [ ] dvb/sec.cpp:1570 setRepeats eDVBSatelliteEquipmentControl::setRepeats(0) 13:52:22.0808 [ ] dvb/sec.cpp:1610 setFastDiSEqC eDVBSatelliteEquipmentControl::setFastDiSEqc(0) 13:52:22.0808 [ ] dvb/sec.cpp:1620 setSeqRepeat eDVBSatelliteEquipmentControl::setSeqRepeat(0) 13:52:22.0809 [ ] dvb/sec.cpp:1600 setCommandOrder eDVBSatelliteEquipmentControl::setCommandOrder(0) 13:52:22.0810 [ ] dvb/sec.cpp:1550 setDiSEqCMode eDVBSatelliteEquipmentControl::setDiSEqcMode(0) 13:52:22.0811 [ ] dvb/sec.cpp:1560 setToneburst eDVBSatelliteEquipmentControl::setToneburst(0) 13:52:22.0811 [ ] dvb/sec.cpp:1580 setCommittedCommand eDVBSatelliteEquipmentControl::setCommittedCommand(4) 13:52:22.0812 [ ] dvb/sec.cpp:1590 setUncommittedCommand eDVBSatelliteEquipmentControl::setUncommittedCommand(0) 13:52:22.0813 [ ] dvb/sec.cpp:1771 addSatellite eDVBSatelliteEquipmentControl::addSatellite(192) 13:52:22.0814 [ ] dvb/sec.cpp:1788 setVoltageMode eDVBSatelliteEquipmentControl::setVoltageMode(0) 13:52:22.0816 [ ] dvb/sec.cpp:1799 setToneMode eDVBSatelliteEquipmentControl::setToneMode(0) 13:52:22.0816 [ ] dvb/sec.cpp:1453 setLNBSlotMask eDVBSatelliteEquipmentControl::setLNBSlotMask(1) 13:52:22.0821 { } Components/NimManager.py:758 canBeCompatible 1 is NOT multitype 13:52:22.0825 { } Components/NimManager.py:232 update slot: 1 configmode: simple 13:52:22.0827 [ ] dvb/sec.cpp:1948 setSlotNotLinked eDVBSatelliteEquipmentControl::setSlotNotLinked(1) 13:52:22.0831 { } Components/NimManager.py:240 update diseqcmode: single 13:52:22.0836 [ ] dvb/sec.cpp:1447 addLNB eDVBSatelliteEquipmentControl::addLNB(1) 13:52:22.0837 [ ] dvb/sec.cpp:1704 setLNBSatCR eDVBSatelliteEquipmentControl::setLNBSatCR(-1) 13:52:22.0838 [ ] dvb/sec.cpp:1525 setLNBSatCRTuningAlgo eDVBSatelliteEquipmentControl::setLNBSatCRTuningAlgo(0) 13:52:22.0839 [ ] dvb/sec.cpp:1537 setLNBBootupTime eDVBSatelliteEquipmentControl::setLNBBootupTime(0) 13:52:22.0840 [ ] dvb/sec.cpp:1513 setLNBSatCRpositionnumber eDVBSatelliteEquipmentControl::setLNBSatCRpositionnumber(1) 13:52:22.0840 [ ] dvb/sec.cpp:1463 setLNBLOFL eDVBSatelliteEquipmentControl::setLNBLOFL(9750000) 13:52:22.0841 [ ] dvb/sec.cpp:1473 setLNBLOFH eDVBSatelliteEquipmentControl::setLNBLOFH(10600000) 13:52:22.0842 [ ] dvb/sec.cpp:1483 setLNBThreshold eDVBSatelliteEquipmentControl::setLNBThreshold(11700000) 13:52:22.0843 [ ] dvb/sec.cpp:1493 setLNBIncreasedVoltage eDVBSatelliteEquipmentControl::setLNBIncreasedVoltage(0) 13:52:22.0844 [ ] dvb/sec.cpp:1570 setRepeats eDVBSatelliteEquipmentControl::setRepeats(0) 13:52:22.0844 [ ] dvb/sec.cpp:1610 setFastDiSEqC eDVBSatelliteEquipmentControl::setFastDiSEqc(0) 13:52:22.0846 [ ] dvb/sec.cpp:1620 setSeqRepeat eDVBSatelliteEquipmentControl::setSeqRepeat(0) 13:52:22.0846 [ ] dvb/sec.cpp:1600 setCommandOrder eDVBSatelliteEquipmentControl::setCommandOrder(0) 13:52:22.0847 [ ] dvb/sec.cpp:1550 setDiSEqCMode eDVBSatelliteEquipmentControl::setDiSEqcMode(0) 13:52:22.0848 [ ] dvb/sec.cpp:1560 setToneburst eDVBSatelliteEquipmentControl::setToneburst(0) 13:52:22.0849 [ ] dvb/sec.cpp:1580 setCommittedCommand eDVBSatelliteEquipmentControl::setCommittedCommand(4) 13:52:22.0849 [ ] dvb/sec.cpp:1590 setUncommittedCommand eDVBSatelliteEquipmentControl::setUncommittedCommand(0) 13:52:22.0850 [ ] dvb/sec.cpp:1771 addSatellite eDVBSatelliteEquipmentControl::addSatellite(192) 13:52:22.0851 [ ] dvb/sec.cpp:1788 setVoltageMode eDVBSatelliteEquipmentControl::setVoltageMode(0) 13:52:22.0852 [ ] dvb/sec.cpp:1799 setToneMode eDVBSatelliteEquipmentControl::setToneMode(0) 13:52:22.0853 [ ] dvb/sec.cpp:1453 setLNBSlotMask eDVBSatelliteEquipmentControl::setLNBSlotMask(2) 13:52:22.0858 { } Components/NimManager.py:327 update sec config completed 13:52:22.0863 { } Components/NimManager.py:2265 InitNimManager [NimManager] slotname = A, slotdescription = BCM7346 (internal), multitype = False, current type = DVB-S2 13:52:22.0869 { } Components/NimManager.py:2265 InitNimManager [NimManager] slotname = B, slotdescription = BCM7346 (internal), multitype = False, current type = DVB-S2 13:52:22.0873 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:22.0878 { } Components/NimManager.py:758 canBeCompatible 1 is NOT multitype 13:52:22.7841 { } Screens/InfoBarGenerics.py:6361 ~~~~ read box informations ~~~~~~~~~ 13:52:22.7850 { } Screens/InfoBarGenerics.py:6362 MachineName = F1 13:52:22.7854 { } Screens/InfoBarGenerics.py:6363 MachineBrand = Formuler 13:52:22.7859 { } Screens/InfoBarGenerics.py:6364 BoxType = formuler1 13:52:22.7862 { } Screens/InfoBarGenerics.py:6365 getMachineBuild = formuler1 13:52:22.7873 { } Screens/InfoBarGenerics.py:6366 ChipString = 7356 13:52:22.7879 { } Screens/InfoBarGenerics.py:6367 OEM = formuler 13:52:22.7890 { } Screens/InfoBarGenerics.py:6368 Driverdate = 13:52:22.7894 { } Screens/InfoBarGenerics.py:6369 Imageversion = 6.2 13:52:22.7900 { } Screens/InfoBarGenerics.py:6370 Imagebuild = 108 13:52:22.7903 { } Screens/InfoBarGenerics.py:6371 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ 13:52:23.2687 { } mytest.py:74 useSyncUsingChanged [Time By]: Transponder 13:52:23.2690 [ ] dvb/epgcache.cpp:470 timeUpdated [EPGC] time updated.. but cache file not set yet.. dont start epg!! executing main 13:52:29.4425 { } Components/Ipkg.py:19 opkgAddDestination [Ipkg] Added to OPKG destinations: / 13:52:29.4910 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/Roboto-Bold.ttf...OK (Regular) 13:52:29.5251 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/Roboto-BoldItalic.ttf...OK (Italic) 13:52:29.5369 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/Roboto-Black.ttf...OK (Bold) 13:52:29.5476 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/Roboto-BlackItalic.ttf...OK (Boldit) 13:52:29.5566 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (Subtitlefont) 13:52:29.5727 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/lcd.ttf...OK (LCD) 13:52:29.5821 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/ae_AlMateen.ttf...OK (Replacement) 13:52:29.5828 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/tuxtxt.ttf...OK (Console) 13:52:29.6059 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/lcd.ttf...OK (LCD) 13:52:29.6068 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/ae_AlMateen.ttf...OK (Replacement) 13:52:29.6076 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/tuxtxt.ttf...OK (Console) 13:52:29.6128 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/enigma2/XionHDF/fonts/nmsbd2.ttf...OK (Arial) 13:52:29.6237 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/enigma2/XionHDF/fonts/NotoSans-Regular.ttf...OK (Regular) 13:52:29.6307 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/enigma2/XionHDF/fonts/NotoSans-Bold.ttf...OK (Regular2) 13:52:29.6384 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/enigma2/XionHDF/fonts/meteocons.ttf...OK (Meteo) 13:52:29.8036 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/LiberationSans-Regular.ttf...OK (Subs) 13:52:29.8114 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/LiberationSans-Italic.ttf...OK (Subsi) 13:52:29.8190 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/LiberationSans-Bold.ttf...OK (Subsb) 13:52:29.8284 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/LiberationSans-BoldItalic.ttf...OK (Subsz) 13:52:29.8399 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (FdLcD) 13:52:29.8549 { W } Components/InputDevice.py:65 getInputDeviceType Unknown device type: RC for hdmi_cec 13:52:29.8588 { W } Components/InputDevice.py:65 getInputDeviceType Unknown device type: front panel 13:52:29.9382 { } Components/Keyboard.py:32 activateKeyboardMap Activating keymap: Dreambox Keyboard Deutsch 13:52:29.9417 { } Components/Language.py:89 activateLanguage Activating language Deutsch 13:52:30.5670 { } Components/AVSwitch.py:322 setWss [VideoMode] setting wss: auto 13:52:30.5679 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 13:52:30.5693 { } Components/AVSwitch.py:345 setPolicy169 [VideoMode] setting policy2: scale 13:52:30.5850 { } Components/AVSwitch.py:928 setScaler_sharpness [VideoMode] setting scaler_sharpness to: 0000000D 13:52:30.5873 { } Components/AVSwitch.py:164 setMode [VideoMode] setMode - port: HDMI, mode: 1080p, rate: 50Hz 13:52:30.6250 [ ] dvb/db.cpp:1188 renumberBouquet [eDVBDB] Renumbering... 13:52:30.7487 { } Components/EpgLoadSave.py:35 stop [EPGC Load] Poller disabled. 13:52:30.7493 { } Components/EpgLoadSave.py:74 stop [EPGC Save] Poller disabled. 13:52:30.8022 [ ] dvb/epgcache.cpp:442 setCacheFile [EPGC] setCacheFile read/write epg data from/to '/media/hdd/epg.dat' 13:52:30.8024 [ ] dvb/epgcache.cpp:454 timeUpdated [EPGC] time updated.. start EPG Mainloop 13:52:31.5622 { } Components/Lcd.py:262 setScrollspeed setLCDScrollspeed 150 13:52:31.5642 { } Components/Lcd.py:255 setRepeat setLCDRepeat 1 13:52:31.5660 { } Components/Lcd.py:194 setMode setLCDMode 0 13:52:31.5707 { } Components/Lcd.py:230 setPower setLCDPower 1 13:52:31.5730 { } Components/Lcd.py:242 setShowoutputresolution setLCDShowoutputresolution 1 13:52:31.5926 { } Screens/UserInterfacePositioner.py:66 InitOsd Setting OSD position: 17 686 16 543 13:52:31.5934 { } Screens/UserInterfacePositioner.py:70 setOSDAlpha Setting OSD alpha: 255 13:52:31.5949 { } Screens/UserInterfacePositioner.py:80 set3DMode Setting 3D mode: off 13:52:31.5962 { } Screens/UserInterfacePositioner.py:102 set3DZnorm Setting 3D depth: 50 13:52:31.6054 { } Components/EpgLoadSave.py:74 stop [EPGC Save] Poller disabled. 13:52:31.6063 { } Components/EpgLoadSave.py:35 stop [EPGC Load] Poller disabled. 13:52:31.6214 [ W ] dvb_ci/dvbci.cpp:326 getSlot FIXME: request for unknown slot 13:52:31.6261 [ W ] dvb_ci/dvbci.cpp:326 getSlot FIXME: request for unknown slot 13:52:31.6293 [ W ] dvb_ci/dvbci.cpp:326 getSlot FIXME: request for unknown slot 13:52:31.7752 [ ] dvb/epgcache.cpp:1378 load [EPGC] 69442 events read from /media/hdd/epg.dat 13:52:32.8913 { } Plugins/Extensions/CoverFind/__init__.py:13 localeInit [CoverFind] set language to de 13:52:32.9151 { } Plugins/Extensions/CoverFind/__init__.py:13 localeInit [CoverFind] set language to de 13:52:32.9230 { } Plugins/Extensions/CoverFind/__init__.py:19 _ [CoverFind] fallback to default Enigma2 Translation for CoverFind 13:52:32.9348 { } Plugins/Extensions/CoverFind/__init__.py:19 _ [CoverFind] fallback to default Enigma2 Translation for CoverFind 13:52:35.7939 { } Plugins/Extensions/MediaPortal/__init__.py:16 _ ('[MediaPortal] fallback to default translation for', 'Wall') 13:52:35.8495 { } Plugins/Extensions/MediaPortal/__init__.py:16 _ ('[MediaPortal] fallback to default translation for', 'PVR/VIDEO') 13:52:35.8822 { } Plugins/Extensions/MediaPortal/__init__.py:16 _ ('[MediaPortal] fallback to default translation for', 'PVR/VIDEO') 13:52:35.9036 { } Plugins/Extensions/MediaPortal/__init__.py:16 _ ('[MediaPortal] fallback to default translation for', 'PVR/VIDEO') 13:52:35.9071 { } Plugins/Extensions/MediaPortal/__init__.py:16 _ ('[MediaPortal] fallback to default translation for', 'EPG/INFO') 13:52:38.8973 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/lib/enigma2/python/Plugins/Extensions/TVSpielfilm/font/Sans.ttf...OK (Sans) 13:52:39.1590 [ E ] base/cfile.h:17 CFile error /usr/share/enigma2/skin_default/expandable.png [No such file or directory] 13:52:39.1591 [ ] gdi/epng.cpp:24 loadPNG [ePNG] couldn't open /usr/share/enigma2/skin_default/expandable.png 13:52:39.1605 [ E ] base/cfile.h:17 CFile error /usr/share/enigma2/skin_default/expanded.png [No such file or directory] 13:52:39.1606 [ ] gdi/epng.cpp:24 loadPNG [ePNG] couldn't open /usr/share/enigma2/skin_default/expanded.png 13:52:39.3753 { } Plugins/Extensions/PiconManager/__init__.py:13 localeInit [PiconManager] set language to de 13:52:39.3932 { } Plugins/Extensions/PiconManager/__init__.py:13 localeInit [PiconManager] set language to de ln: /usr/bin/ipkg: File exists ln: /usr/bin/ipkg-cl: File exists ####################### running HDFreaks autostart scripts ####################### check scripts and create symlinks MP skins are currently installed 13:52:40.9046 { } Plugins/Extensions/HDF-Toolbox/plugin.py:194 symlink Extensions exists 13:52:40.9051 { } Plugins/Extensions/HDF-Toolbox/plugin.py:203 symlink SystemPlugins exists 13:52:41.9890 { } Plugins/Extensions/Infopanel/plugin.py:121 [Info-Panel] boxversion: formuler1 13:52:43.8661 { E } Tools/StbHardware.py:22 getFPVersion getFPVersion failed! 13:52:43.8684 { } plugin/controllers/models/info.py:379 getInfo [OpenWebif] -D- tuner '0' 'BCM7346 (internal)' 'Tuner A' 13:52:43.8692 { } plugin/controllers/models/info.py:379 getInfo [OpenWebif] -D- tuner '1' 'BCM7346 (internal)' 'Tuner B' 13:52:44.0671 { } Plugins/Extensions/EPGRefresh/EpgLoadSaveRefresh.py:35 stop [EPGC Load] Poller disabled. 13:52:44.0678 { } Plugins/Extensions/EPGRefresh/EpgLoadSaveRefresh.py:92 stop [EPGC Save] Poller disabled. 13:52:44.6526 [ E ] base/cfile.h:17 CFile error /usr/lib/enigma2/python/Plugins/Extensions/PictureCenterFS/skin//pictures/mov.png [No such file or directory] 13:52:44.6528 [ ] gdi/epng.cpp:24 loadPNG [ePNG] couldn't open /usr/lib/enigma2/python/Plugins/Extensions/PictureCenterFS/skin//pictures/mov.png 13:52:46.5427 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:46.6069 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:46.6543 { } Components/NimManager.py:758 canBeCompatible 0 is NOT multitype 13:52:47.0054 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 8E 00 13:52:47.0989 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 04 13:52:47.1663 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 82 10 00 13:52:47.2832 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 8E 00 13:52:47.6889 [ ] ../../../git/src/serviceapp/serviceapp.cpp:1566 serviceapp_set_setting [serviceapp_set_setting] setting serviceexteplayer3 options 13:52:47.6894 [ ] ../../../git/src/serviceapp/serviceapp.cpp:1570 serviceapp_set_setting [serviceapp_set_setting] setting servicegstplayer options 13:52:47.6899 [ ] ../../../git/src/serviceapp/serviceapp.cpp:1574 serviceapp_set_setting [serviceapp_set_setting] setting servicemp3 options 13:52:47.6912 [ ] ../../../git/src/serviceapp/serviceapp.cpp:1457 gstplayer_set_setting [gstplayer_set_setting] setting servicegstplayer options 13:52:47.6918 [ ] ../../../git/src/serviceapp/serviceapp.cpp:1461 gstplayer_set_setting [gstplayer_set_setting] setting servicemp3 options 13:52:47.6941 [ ] ../../../git/src/serviceapp/serviceapp.cpp:1517 exteplayer3_set_setting [exteplayer3_set_setting] setting serviceextplayer3 options 13:52:47.6958 [ ] ../../../git/src/serviceapp/serviceapp.cpp:1521 exteplayer3_set_setting [exteplayer3_set_setting] setting servicemp3 options 13:52:47.7189 { } Plugins/Extensions/EPGImport/log.py:25 write [EPGImport] autostart (0) occured at 1509886367.72 13:52:47.7213 { } Plugins/Extensions/HDF-Toolbox/plugin.py:242 iptvUpdate [HDF-Toolbox]: IPTV autoupdate 13:52:48.0063 { } Plugins/Extensions/HDF-Toolbox/downloader.py:934 doIptvUpdate [HDF-Toolbox] IPTV list update 13:52:48.6384 { } Plugins/Extensions/AutoTimer/AutoPoller.py:19 __init__ [AutoTimer] Auto Poll Enabled 13:52:48.6481 { } Plugins/SystemPlugins/CommonInterfaceAssignment/plugin.py:630 autostart [CI_Assignment] activating ci configs: 13:52:48.6497 { } Plugins/SystemPlugins/Hotplug/plugin.py:58 autostart starting hotplug handler 13:52:48.6671 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/0/active_antenna_power [No such file or directory] 13:52:48.6691 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Club der roten Bänder, begin=Mon Nov 6 20:12:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.6745 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Club der roten Bänder, begin=Mon Nov 6 21:17:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.6804 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Club der roten Bänder - Die Geschichte lebt weiter, begin=Mon Nov 6 22:22:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.6874 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Hubert und Staller, begin=Wed Nov 8 18:47:00 2017, serviceref=1:0:19:283D:3FB:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.6954 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Outlander, begin=Wed Nov 8 20:12:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.7039 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Outlander, begin=Wed Nov 8 21:17:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.7134 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Hubert und Staller (93), begin=Wed Oct 4 18:47:00 2017, serviceref=1:0:19:283D:3FB:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.7222 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Outlander, begin=Wed Oct 4 23:12:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.7308 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Outlander, begin=Wed Oct 4 22:07:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.7411 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Hubert und Staller - Die ins Gras beißen, begin=Thu Oct 5 20:12:00 2017, serviceref=1:0:19:283D:3FB:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.7513 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Club der roten Bänder, begin=Mon Oct 9 21:12:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.7606 { } RecordTimer.py:1226 record [Timer] Record RecordTimerEntry(name=Club der roten Bänder, begin=Mon Oct 9 20:12:00 2017, serviceref=1:0:1:2F1C:441:1:C00000:0:0:0:, justplay=0, isAutoTimer=1) 13:52:48.7640 { } PowerTimer.py:1031 record [PowerTimer] PowerTimerEntry(type=autodeepstandby, begin=Fri Apr 22 12:05:30 2016 Disabled) 13:52:48.7654 { } PowerTimer.py:1031 record [PowerTimer] PowerTimerEntry(type=autostandby, begin=Fri Apr 22 12:05:39 2016 Disabled) 13:52:48.7665 { } Navigation.py:68 __init__ ==================================================================================================== 13:52:48.7669 { } Navigation.py:70 __init__ [NAVIGATION] wakeup time from deep-standby expected: *** Sun Nov 5 18:55:38 2017 *** 13:52:48.7673 { } Navigation.py:71 __init__ [NAVIGATION] timer wakeup detection window: Sun Nov 5 18:50:38 2017 - Sun Nov 5 19:05:38 2017 13:52:48.7676 { } Navigation.py:72 __init__ ---------------------------------------------------------------------------------------------------- 13:52:48.7680 { } Navigation.py:82 __init__ [NAVIGATION] starting deepstandby-workaround 13:52:48.7682 { } Navigation.py:95 __init__ [NAVIGATION] was timer wakeup = False 13:52:48.7686 { } Navigation.py:96 __init__ [NAVIGATION] current time is Sun Nov 5 13:52:48 2017 13:52:48.7689 { } Navigation.py:116 __init__ [NAVIGATION] next 'plugin-timer' starts at Sun Nov 5 19:00:38 2017 13:52:48.7692 { } Navigation.py:119 __init__ ==================================================================================================== 13:52:48.8754 { } Plugins/Extensions/HbbTV/vbcfg.py:76 LogEntry [browser] browser start 13:52:48.8774 { } skin.py:1093 readSkin [SKIN] Parsing embedded skin 13:52:48.8799 { } skin.py:1313 readSkin [SKIN] processing screen : 13:52:48.8850 { } skin.py:1313 readSkin [SKIN] processing screen SimpleSummary: 13:52:48.9022 { } Plugins/Extensions/CustomSubservices/plugin.py:386 main [CustomSubservicesPlugin] start 13:52:48.9025 { } Plugins/Extensions/CustomSubservices/plugin.py:386 main 13:52:49.1820 { } Plugins/Extensions/EPGImport/log.py:25 write [EPGImport] autostart (0) occured at 1509886369.18 13:52:49.1850 { } Plugins/Extensions/EPGImport/log.py:25 write [EPGImport] WakeUpTime now set to 1509904838 (now=1509886369) 13:52:49.1857 { } Plugins/Extensions/EPGImport/log.py:25 write [EPGImport] onBootStartCheck 13:52:49.1875 { } Plugins/Extensions/EPGImport/log.py:25 write [EPGImport] now=1509886369 wake=1509904838 wake-now=18469 13:52:49.1882 { } Plugins/Extensions/EPGImport/log.py:25 write [EPGImport] is boot 13:52:49.1892 { } Plugins/Extensions/EPGImport/log.py:25 write [EPGImport] not starting import - is restart enigma2 13:52:49.1953 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:52:49,193 - INFO - InfoBarTunerState start 13:52:49.2100 { W } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:52:49,209 - DEBUG - [IBTS Plugins]: Files: ['Live', 'StreamOpenWebIf', 'Timers', 'Unknown', '__init__', 'PiP', 'Records', 'StreamServer', 'StreamWebIf'] 13:52:49.2933 { W } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:52:49,292 - DEBUG - [IBTS Plugins]: {'StreamWebIf': , 'StreamServer': , 'Unknown': , 'StreamOpenWebIf': , 'Records': , 'Live': , 'Timers': , 'PiP': } 13:52:49.3002 { } skin.py:1313 readSkin [SKIN] processing screen TunerState: 13:52:49.4344 [ ] dvb/volume.cpp:152 setVolume Setvolume: 100 100 (raw) 13:52:49.4347 [ ] dvb/volume.cpp:158 setVolume [AUDIO_SET_MIXER] Setvolume left: 0 right: 0 (-1db) 13:52:49.4387 { } skin.py:1093 readSkin [SKIN] Parsing embedded skin 13:52:49.4404 { } skin.py:1313 readSkin [SKIN] processing screen : 13:52:49.4442 { } skin.py:1313 readSkin [SKIN] processing screen SimpleSummary: 13:52:49.4661 { } Plugins/Extensions/MediaInfo/plugin.py:695 autostart [MediaInfo] READ OLD JOBS !!! 13:52:49.4697 { } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: +++ EMC git20170817 startup 13:52:49.4828 { } Plugins/Extensions/EnhancedMovieCenter/EnhancedMovieCenter.py:97 checkImg [EMC] checkImg is not Vti 13:52:49.4832 { } Plugins/Extensions/EnhancedMovieCenter/EnhancedMovieCenter.py:104 checkImg [EMC] checkImg is newPiconRenderer 13:52:49.4849 { } Plugins/SystemPlugins/AutomaticVolumeAdjustment/AutomaticVolumeAdjustment.py:38 __init__ [AutomaticVolumeAdjustment] Starting AutomaticVolumeAdjustment... 13:52:49.4856 { } Plugins/SystemPlugins/AutomaticVolumeAdjustment/AutomaticVolumeAdjustmentConfig.py:57 loadConfigFile [AutomaticVolumeAdjustmentConfig] Loading config file... 13:52:49.4989 { } Plugins/SystemPlugins/AutomaticVolumeAdjustment/AutomaticVolumeAdjustmentConfig.py:85 initConfig [AutomaticVolumeAdjustmentConfig] Loaded 0 entries from config file... 13:52:49.4993 { } Plugins/SystemPlugins/AutomaticVolumeAdjustment/AutomaticVolumeAdjustment.py:60 initializeConfigValues [AutomaticVolumeAdjustment] initialize config values... 13:52:49.5007 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/lib/enigma2/python/Plugins/Extensions/MediaPortal/resources/mediaportal1.ttf...OK (mediaportal) 13:52:49.5158 < > gdi/font.cpp:159 AddFont [FONT] adding font /usr/lib/enigma2/python/Plugins/Extensions/MediaPortal/resources/mediaportal_clean.ttf...OK (mediaportal_clean) 13:52:49.5997 { } skin.py:1313 readSkin [SKIN] processing screen Screensaver: 13:52:49.7740 { } skin.py:1313 readSkin [SKIN] processing screen SecondInfoBar: 13:52:50.1621 { W } skin.py:414 applyAll [SKIN] Attribute "alphatest" with value "blend" in object of type "eLabel" is not implemented 13:52:50.2285 { W } skin.py:414 applyAll [SKIN] Attribute "valign" with value "center" in object of type "ePixmap" is not implemented 13:52:50.2483 { W } skin.py:414 applyAll [SKIN] Attribute "valign" with value "center" in object of type "ePixmap" is not implemented 13:52:50.2683 { W } skin.py:414 applyAll [SKIN] Attribute "valign" with value "center" in object of type "ePixmap" is not implemented 13:52:50.2875 { W } skin.py:414 applyAll [SKIN] Attribute "valign" with value "center" in object of type "ePixmap" is not implemented 13:52:50.4947 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_yellow in 13:52:50.4957 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_blue in 13:52:50.4978 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element epg_description in 13:52:50.4988 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_green in 13:52:50.4997 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_red in 13:52:50.5008 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element channel in 13:52:50.8181 { } skin.py:1093 readSkin [SKIN] Parsing embedded skin 13:52:50.8201 { } skin.py:1313 readSkin [SKIN] processing screen : 13:52:50.8870 { } skin.py:1313 readSkin [SKIN] processing screen ChannelSelection: 13:52:50.9733 { W } skin.py:414 applyAll [SKIN] Attribute "valign" with value "left" in object of type "eWidget" is not implemented 13:52:50.9888 { W } skin.py:414 applyAll [SKIN] Attribute "colorServiceRecording" with value "red" in object of type "eListbox" is not implemented 13:52:51.0960 { } skin.py:1313 readSkin [SKIN] processing screen SlimChannelSelection: 13:52:51.1160 { E } skin.py:1266 process [SKIN] SKIN ERROR in screen 'SlimChannelSelection' widget 'widget': {XionHDF/skin.xml}: source 'PrimeTime' was not found in screen 'SlimChannelSelection'!. Please contact the skin's author! 13:52:51.1562 { W } skin.py:414 applyAll [SKIN] Attribute "colorServiceRecording" with value "red" in object of type "eListbox" is not implemented 13:52:51.2516 { } skin.py:1313 readSkin [SKIN] processing screen RdsInfoDisplay: 13:52:51.2857 { } skin.py:1313 readSkin [SKIN] processing screen RdsInfoDisplaySummary: 13:52:51.2951 { } skin.py:1313 readSkin [SKIN] processing screen UnhandledKey: 13:52:51.3301 { } skin.py:1313 readSkin [SKIN] processing screen Dish: 13:52:51.3358 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element From in 13:52:51.3368 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element Goto in 13:52:51.3380 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element tunerName in 13:52:51.3389 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element turnSpeed in 13:52:51.3399 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element posGoto in 13:52:51.3410 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element posFrom in 13:52:51.3634 { W } skin.py:414 applyAll [SKIN] Attribute "itemHeight" with value "30" in object of type "ePixmap" is not implemented 13:52:51.3639 { W } skin.py:414 applyAll [SKIN] Attribute "font" with value "Regular;22" in object of type "ePixmap" is not implemented 13:52:51.3653 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element turnTime in 13:52:51.3663 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element Tuner in 13:52:51.3708 { } skin.py:1313 readSkin [SKIN] processing screen BufferIndicator: 13:52:51.3899 { } skin.py:1313 readSkin [SKIN] processing screen TimeshiftState: 13:52:51.4088 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element PTSSeekPointer in 13:52:51.4098 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element statusicon in 13:52:51.4108 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element eventname in 13:52:51.4130 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element PTSSeekBack in 13:52:51.4143 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element speed in 13:52:51.4274 { } skin.py:1313 readSkin [SKIN] processing screen SubtitleDisplay: 13:52:51.4723 { } skin.py:1313 readSkin [SKIN] processing screen InfoBar: 13:52:51.5998 { W } skin.py:414 applyAll [SKIN] Attribute "alphatest" with value "blend" in object of type "eLabel" is not implemented 13:52:51.6022 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_blue in 13:52:51.6035 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_red in 13:52:51.6081 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_green in 13:52:51.6106 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_yellow in 13:52:51.6225 [ ] gdi/grc.cpp:193 thread main thread is non-idle! display spinner! 13:52:51.6680 { } skin.py:1313 readSkin [SKIN] processing screen InfoBarSummary: 13:52:51.7224 { } skin.py:1313 readSkin [SKIN] processing screen Volume: 13:52:51.7579 { } skin.py:1313 readSkin [SKIN] processing screen Mute: 13:52:51.7814 [ ] dvb/volume.cpp:152 setVolume Setvolume: 18 18 (raw) 13:52:51.7816 [ ] dvb/volume.cpp:158 setVolume [AUDIO_SET_MIXER] Setvolume left: 52 right: 52 (-1db) 13:52:51.8205 { } skin.py:1313 readSkin [SKIN] processing screen Scart: 13:52:51.8539 { } skin.py:1313 readSkin [SKIN] processing screen AutoVideoModeLabel: 13:52:51.8608 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//bouquets.tv 13:52:51.8620 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.favourites.tv 13:52:51.8638 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 60 entries in Bouquet userbouquet.favourites.tv 13:52:51.8654 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.dbe00.tv 13:52:51.8682 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 60 entries in Bouquet userbouquet.dbe00.tv 13:52:51.8686 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.iptv_epg_germany.tv 13:52:51.8708 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 53 entries in Bouquet userbouquet.iptv_epg_germany.tv 13:52:51.8711 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.iptv_webcams.tv 13:52:51.9001 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 855 entries in Bouquet userbouquet.iptv_webcams.tv 13:52:51.9017 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.mobil_sd.tv 13:52:51.9040 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 45 entries in Bouquet userbouquet.mobil_sd.tv 13:52:51.9048 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.kinder.tv 13:52:51.9061 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 6 entries in Bouquet userbouquet.kinder.tv 13:52:51.9076 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.die_dritten.tv 13:52:51.9097 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 28 entries in Bouquet userbouquet.die_dritten.tv 13:52:51.9108 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.lokale_sender.tv 13:52:51.9126 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 15 entries in Bouquet userbouquet.lokale_sender.tv 13:52:51.9138 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.musik.tv 13:52:51.9151 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 3 entries in Bouquet userbouquet.musik.tv 13:52:51.9166 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.special_interest.tv 13:52:51.9184 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 15 entries in Bouquet userbouquet.special_interest.tv 13:52:51.9194 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.sky_deutschland.tv 13:52:51.9210 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 4 entries in Bouquet userbouquet.sky_deutschland.tv 13:52:51.9225 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.sky_d_hdtv.tv 13:52:51.9249 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 57 entries in Bouquet userbouquet.sky_d_hdtv.tv 13:52:51.9256 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.orf.tv 13:52:51.9269 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 6 entries in Bouquet userbouquet.orf.tv 13:52:51.9288 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.orf_2_regional.tv 13:52:51.9301 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 8 entries in Bouquet userbouquet.orf_2_regional.tv 13:52:51.9315 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.music_astra.tv 13:52:51.9328 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 6 entries in Bouquet userbouquet.music_astra.tv 13:52:51.9350 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.mtv_network.tv 13:52:51.9366 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 6 entries in Bouquet userbouquet.mtv_network.tv 13:52:51.9380 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.fta_tv_int_astra.tv 13:52:51.9399 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 40 entries in Bouquet userbouquet.fta_tv_int_astra.tv 13:52:51.9412 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.austriasat.tv 13:52:51.9429 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 8 entries in Bouquet userbouquet.austriasat.tv 13:52:51.9447 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.hdsd.tv 13:52:51.9473 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 49 entries in Bouquet userbouquet.hdsd.tv 13:52:51.9490 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.hd+.tv 13:52:51.9509 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 20 entries in Bouquet userbouquet.hd+.tv 13:52:51.9521 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.oesterreich_hdsd.tv 13:52:51.9542 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 17 entries in Bouquet userbouquet.oesterreich_hdsd.tv 13:52:51.9552 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.hd_austria.tv 13:52:51.9570 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 14 entries in Bouquet userbouquet.hd_austria.tv 13:52:51.9587 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.LastScanned.tv 13:52:51.9882 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 1339 entries in Bouquet userbouquet.LastScanned.tv 13:52:51.9884 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 23 entries in Bouquet bouquets.tv 13:52:51.9891 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//bouquets.radio 13:52:51.9900 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.favourites.radio 13:52:51.9922 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 96 entries in Bouquet userbouquet.favourites.radio 13:52:51.9930 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.orf_radio.radio 13:52:51.9942 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 13 entries in Bouquet userbouquet.orf_radio.radio 13:52:51.9950 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.astra_radio_int.radio 13:52:51.9962 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 9 entries in Bouquet userbouquet.astra_radio_int.radio 13:52:51.9971 [ ] dvb/db.cpp:1034 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.csf_radio.radio 13:52:51.9984 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 36 entries in Bouquet userbouquet.csf_radio.radio 13:52:51.9986 [ ] dvb/db.cpp:1144 loadBouquet [eDVBDB] 4 entries in Bouquet bouquets.radio 13:52:51.9987 [ ] dvb/db.cpp:1188 renumberBouquet [eDVBDB] Renumbering... 13:52:52.0016 { } mytest.py:603 runScreenTest lastshutdown=True (True = last shutdown was OK) 13:52:52.0020 { } mytest.py:604 runScreenTest NOK shutdown action=normal 13:52:52.0024 { } mytest.py:605 runScreenTest bootup action=normal killall: showiframe: no process killed 13:52:52.2458 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.2463 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.2470 { } Screens/LogManager.py:68 TrimTimerJob [LogManager] Trim Poll Started 13:52:52.2516 { } Screens/LogManager.py:72 TrashTimerJob [LogManager] Trash Poll Started 13:52:52.2524 { } Screens/LogManager.py:116 JobTrash [LogManager] probing folders 13:52:52.2821 { } Screens/LogManager.py:128 JobTrash [LogManager] found following log's: ['/home/root/logs'] 13:52:52.2826 { } Screens/LogManager.py:131 JobTrash [LogManager] looking in: /home/root/logs 13:52:52.2843 { } Screens/LogManager.py:159 JobTrash [LogManager] /home/root/logs: bytesToRemove -10432588 13:52:52.2858 { } Components/NetworkTime.py:41 update_schedule [NTP]: setting E2 time: 1509886372.29 13:52:52.2941 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 04 13:52:52.3619 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 82 10 00 13:52:52.4786 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 8E 00 13:52:52.6876 { } Plugins/Extensions/Infopanel/CamStart.py:90 timerEvent [CAMSTARTER] No Cam to Start, Exit 13:52:52.7027 { } Navigation.py:244 playService playing 1:0:19:2B66:3F3:1:C00000:0:0:0: 13:52:52.7797 { } Screens/InfoBarGenerics.py:5575 __serviceStarted new service started! trying to download cuts! 13:52:52.7808 [ ] dvb/dvb.cpp:1398 allocateChannel allocate channel.. 03f3:0001 13:52:52.7809 [ ] dvb/sec.cpp:72 canTune [eDVBSatelliteEquipmentControl] direct_connected 1 13:52:52.7810 [ ] dvb/sec.cpp:111 canTune [eDVBSatelliteEquipmentControl] canTune 1 13:52:52.7810 [ ] dvb/sec.cpp:126 canTune [eDVBSatelliteEquipmentControl] lnb 0 found 13:52:52.7811 [ ] dvb/sec.cpp:136 canTune [eDVBSatelliteEquipmentControl] 1 option(s) at position 192 13:52:52.7811 [ ] dvb/sec.cpp:150 canTune [eDVBSatelliteEquipmentControl] sat 192 found 13:52:52.7812 [ ] dvb/sec.cpp:194 canTune ret1 15000 13:52:52.7812 [ ] dvb/sec.cpp:240 canTune [eDVBSatelliteEquipmentControl] ret5 15000 13:52:52.7813 [ ] dvb/sec.cpp:257 canTune [eDVBSatelliteEquipmentControl] ret 15000, score old 0 13:52:52.7813 [ ] dvb/sec.cpp:264 canTune [eDVBSatelliteEquipmentControl] score new 15000 13:52:52.7814 [ ] dvb/sec.cpp:279 canTune [eDVBSatelliteEquipmentControl] final score 15005 13:52:52.7815 [ ] dvb/sec.cpp:72 canTune [eDVBSatelliteEquipmentControl] direct_connected 1 13:52:52.7819 [ ] dvb/sec.cpp:111 canTune [eDVBSatelliteEquipmentControl] canTune 2 13:52:52.7820 [ ] dvb/sec.cpp:126 canTune [eDVBSatelliteEquipmentControl] lnb 1 found 13:52:52.7821 [ ] dvb/sec.cpp:136 canTune [eDVBSatelliteEquipmentControl] 1 option(s) at position 192 13:52:52.7822 [ ] dvb/sec.cpp:150 canTune [eDVBSatelliteEquipmentControl] sat 192 found 13:52:52.7822 [ ] dvb/sec.cpp:194 canTune ret1 15000 13:52:52.7823 [ ] dvb/sec.cpp:240 canTune [eDVBSatelliteEquipmentControl] ret5 15000 13:52:52.7824 [ ] dvb/sec.cpp:257 canTune [eDVBSatelliteEquipmentControl] ret 15000, score old 0 13:52:52.7827 [ ] dvb/sec.cpp:264 canTune [eDVBSatelliteEquipmentControl] score new 15000 13:52:52.7829 [ ] dvb/sec.cpp:279 canTune [eDVBSatelliteEquipmentControl] final score 15005 13:52:52.7840 [ ] dvb/frontend.cpp:718 openFrontend m_need_delivery_system_workaround = 0 13:52:52.7842 [ ] dvb/frontend.cpp:720 openFrontend opening frontend 0 13:52:52.7848 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/0/active_antenna_power [No such file or directory] 13:52:52.7877 [ ] dvb/frontend.cpp:2913 tune tune tuner 0 13:52:52.7878 [ ] dvb/sec.cpp:72 canTune [eDVBSatelliteEquipmentControl] direct_connected 1 13:52:52.7879 [ ] dvb/sec.cpp:111 canTune [eDVBSatelliteEquipmentControl] canTune 1 13:52:52.7879 [ ] dvb/sec.cpp:126 canTune [eDVBSatelliteEquipmentControl] lnb 0 found 13:52:52.7880 [ ] dvb/sec.cpp:136 canTune [eDVBSatelliteEquipmentControl] 1 option(s) at position 192 13:52:52.7881 [ ] dvb/sec.cpp:150 canTune [eDVBSatelliteEquipmentControl] sat 192 found 13:52:52.7881 [ ] dvb/sec.cpp:194 canTune ret1 15000 13:52:52.7882 [ ] dvb/sec.cpp:240 canTune [eDVBSatelliteEquipmentControl] ret5 15000 13:52:52.7882 [ ] dvb/sec.cpp:257 canTune [eDVBSatelliteEquipmentControl] ret 15000, score old 0 13:52:52.7883 [ ] dvb/sec.cpp:264 canTune [eDVBSatelliteEquipmentControl] score new 15000 13:52:52.7884 [ ] dvb/sec.cpp:279 canTune [eDVBSatelliteEquipmentControl] final score 15005 13:52:52.7884 [ ] dvb/sec.cpp:1053 prepare [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff 13:52:52.7886 [ ] dvb/frontend.cpp:2833 prepare_sat frontend 0 prepare_sat System 1 Freq 11362000 Pol 0 SR 22000000 INV 0 FEC 2 orbpos 192 system 1 modulation 2 pilot 2, rolloff 0, is_id 0, pls_mode 0, pls_code 1 13:52:52.7887 [ ] dvb/frontend.cpp:2839 prepare_sat tuning to 1612 MHz 13:52:52.7888 [ ] dvb/dvb.cpp:1875 frontendStateChanged OURSTATE: tuner 0 tuning 13:52:52.7889 [ ] dvb/pmt.cpp:990 tuneExt allocate Channel: res 0 13:52:52.7890 [ ] dvb_ci/dvbci.cpp:787 addPMTHandler [eDVBCIInterfaces] addPMTHandler 1:0:19:2B66:3F3:1:C00000:0:0:0: 13:52:52.7892 [ ] dvb/dvb.cpp:1098 allocateDemux allocate demux 13:52:52.8208 { } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: Setting EPG language: de_DE 13:52:52.8213 [ E ] base/cfile.h:17 CFile error /proc/stb/frontend/0/active_antenna_power [No such file or directory] 13:52:52.8342 [ ] base/e2avahi.cpp:48 activated [Avahi] watch activated: 0x1 13:52:52.8356 [ ] base/ebase.cpp:279 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 39(16) 13:52:52.8841 [ ] base/ebase.cpp:279 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 42(17) 13:52:52.8843 [ ] base/ebase.cpp:279 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 46(16) 13:52:52.8890 { } Components/Task.py:375 jobDone job Components.Task.Job name=Log-Verwaltung #tasks=1 completed with [] in None 13:52:52.8927 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.8931 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.8992 [ ] dvb/frontend.cpp:2203 tuneLoopInt [SEC] set static current limiting 13:52:52.9001 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.9003 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.9004 [ ] dvb/frontend.cpp:2152 tuneLoopInt [SEC] invalidate current switch params 13:52:52.9006 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.9008 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.9009 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.9011 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.9011 [ ] dvb/frontend.cpp:1872 tuneLoopInt [SEC] tuner 0 setVoltage 2 13:52:52.9019 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.9021 [ ] base/e2avahi.cpp:196 avahi_browser_callback [Avahi] Resolving service 'formuler1' of type '_e2stream._tcp' 13:52:52.9099 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:52.9135 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:52.9138 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.9139 [ ] dvb/frontend.cpp:1863 tuneLoopInt [SEC] tuner 0 sleep 10ms 13:52:52.9164 [ ] base/e2avahi.cpp:48 activated [Avahi] watch activated: 0x1 13:52:52.9168 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.9169 [ ] base/e2avahi.cpp:196 avahi_browser_callback [Avahi] Resolving service 'formuler1' of type '_e2stream._tcp' 13:52:52.9177 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:52.9211 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:52.9213 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.9233 [ ] base/e2avahi.cpp:48 activated [Avahi] watch activated: 0x1 13:52:52.9237 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.9247 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:52.9263 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:52.9266 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.9681 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:52.9685 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:52.9703 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:52.9706 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:52.9726 { } Components/Network.py:125 routeFinished 0.0.0.0 13:52:52.9732 { } Components/Network.py:125 routeFinished 192.168 13:52:53.0270 { E } plugin/controllers/SR.py:24 __init__ SerienRecorder plugin not found 13:52:53.0529 { } plugin/httpserver.py:156 buildRootTree [OpenWebif] no plugins to load 13:52:53.0632 { } plugin/httpserver.py:177 HttpdStart [OpenWebif] started on 80 13:52:53.0642 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:53.0657 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:53.0660 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:53.0673 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:53.0683 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:53.0699 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:53.0702 [ ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new 13:52:53.0722 [ ] base/e2avahi.cpp:380 avahi_timeout_free [Avahi] avahi_timeout_free 13:52:53.0724 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:53.0725 [ ] base/e2avahi.cpp:151 avahi_service_try_register [Avahi] Registered formuler1 (_http._tcp) on formuler1:80 13:52:53.0730 [ ] base/ebase.cpp:279 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 40(16) 13:52:53.0777 [ ] dvb/frontend.cpp:2162 tuneLoopInt [SEC] update current switch params 13:52:53.0778 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:53.0780 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:53.0808 [ ] dvb/frontend.cpp:1974 tuneLoopInt [SEC] tuner 0 startTuneTimeout 5000 13:52:53.0814 [ ] dvb/frontend.cpp:1967 tuneLoopInt [SEC] tuner 0 setFrontend: events enabled 13:52:53.0817 [ ] dvb/frontend.cpp:2385 setFrontend setting frontend 0 events: on 13:52:53.0830 [ ] dvb/frontend.cpp:1055 feEvent (0)fe event: status 0, inversion off, m_tuning 1 13:52:53.0831 [ ] dvb/frontend.cpp:1863 tuneLoopInt [SEC] tuner 0 sleep 500ms 13:52:53.3726 [ ] dvb/frontend.cpp:1055 feEvent (0)fe event: status 1f, inversion off, m_tuning 2 13:52:53.3728 [ ] dvb/dvb.cpp:1871 frontendStateChanged OURSTATE: tuner 0 ok 13:52:53.3728 [ ] dvb/dvbtime.cpp:674 DVBChannelStateChanged [eDVBLocalTimerHandler] channel 0x18c1328 running 13:52:53.3733 [ ] dvb/epgcache.cpp:680 DVBChannelStateChanged [eEPGCache] channel 0x18c1328 running 13:52:53.3739 [ ] dvb/dvb.cpp:1477 DVBChannelStateChanged stop release channel timer 13:52:53.3740 [ ] dvb/epgcache.cpp:2071 startChannel [EPGC] next update in 2 sec 13:52:53.3740 [ ] dvb/pmt.cpp:77 channelStateChanged ok ... now we start!! 13:52:53.3742 [ ] service/servicedvb.cpp:1199 serviceEvent eventNewProgramInfo 0 0 13:52:53.3742 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 1 audio stream(s) (17e8), and the pcr pid is 17de, and the text pid is 17f2 13:52:53.3743 [ ] dvb/dvb.cpp:1098 allocateDemux allocate demux 13:52:53.3841 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:52:53.3858 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:52:53.3860 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:52:53.3861 < > dvb/decoder.cpp:702 startPid DMX_SET_PES_FILTER(0x17de) - pcr - ok 13:52:53.3862 < > dvb/decoder.cpp:710 startPid DEMUX_START - pcr - ok 13:52:53.3866 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0x17e8) - audio - ok 13:52:53.3867 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 13:52:53.3869 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 13:52:53.3869 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:52:53.3870 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 13:52:53.3929 [ ] dvb/decoder.cpp:267 eDVBVideo Video Device: /dev/dvb/adapter0/video0 13:52:53.3930 [ ] dvb/decoder.cpp:274 eDVBVideo demux device: /dev/dvb/adapter0/demux0 13:52:53.3931 < > dvb/decoder.cpp:354 startPid VIDEO_SET_STREAMTYPE 1 - ok 13:52:53.3931 < > dvb/decoder.cpp:389 startPid DMX_SET_PES_FILTER(0x17de) - video - ok 13:52:53.3932 < > dvb/decoder.cpp:397 startPid DEMUX_START - video - ok 13:52:53.3933 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 13:52:53.3937 < > dvb/decoder.cpp:412 startPid VIDEO_PLAY - ok 13:52:53.3991 < > dvb/decoder.cpp:779 startPid DMX_SET_PES_FILTER(0x17f2) - ttx - ok 13:52:53.3992 < > dvb/decoder.cpp:787 startPid DEMUX_START - ttx - ok 13:52:53.4042 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:52:53.4044 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:52:53.4045 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:52:53.4046 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:52:53.4047 < > dvb/decoder.cpp:222 setChannel AUDIO_CHANNEL_SELECT(0) - ok 13:52:53.4063 [ ] dvb/teletext.cpp:640 setPageAndMagazine disable teletext subtitles page ffffffffffffffff (und) 13:52:53.4174 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 13:52:53.4178 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 18 13:52:53.4181 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 13:52:53.4188 { } Plugins/Extensions/Volume_adjust/plugin.py:484 __evUpdatedInfo [Volume Adjust] New Channel 13:52:53.4191 { } Plugins/Extensions/Volume_adjust/plugin.py:489 __evUpdatedInfo [Volume Adjust] oldvol = 0 13:52:53.4277 [ ] dvb/cahandler.cpp:267 registerService [eDVBCAService] new service 1:0:19:2B66:3F3:1:C00000:0:0:0: 13:52:53.4278 [ ] dvb/cahandler.cpp:285 registerService [eDVBCAService] add demux 0 to slot 0 service 1:0:19:2B66:3F3:1:C00000:0:0:0: 13:52:53.4820 [ ] dvb/pmt.cpp:202 PATready PATready 13:52:53.4822 [ ] dvb/pmt.cpp:213 PATready PAT TSID: 0x03f3 (1011) 13:52:53.4823 [ ] dvb/pmt.cpp:242 PATready use pmtpid 17d4 for service_id 2b66 13:52:53.4883 [ ] service/servicedvb.cpp:1199 serviceEvent eventNewProgramInfo 0 0 13:52:53.4924 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 4 audio stream(s) (17e8, 17e9, 17ea, 17eb), and the pcr pid is 17de, and the text pid is 17f2 13:52:53.4932 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:52:53.4938 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:52:53.4940 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:52:53.5110 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 13:52:53.5114 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 18 13:52:53.5118 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 13:52:53.5207 [ ] dvb_ci/dvbci.cpp:867 gotPMT [eDVBCIInterfaces] gotPMT 13:52:53.5833 [ ] dvb/frontend.cpp:2203 tuneLoopInt [SEC] set dynamic current limiting 13:52:53.6031 [ ] dvb/pmt.cpp:943 SDTScanEvent sdt update done! 13:52:53.9305 [ ] dvb/pmt.cpp:288 AITready AITready 13:52:53.9307 [ ] dvb/pmt.cpp:307 AITready Section Length : 432, Total Section Length : 435 13:52:53.9309 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 1 13:52:53.9312 [ ] dvb/pmt.cpp:282 saveData Save Data Len : [435] 13:52:53.9316 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 6 13:52:53.9318 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 2 13:52:53.9319 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 7 13:52:53.9320 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 8 13:52:53.9320 [ ] dvb/pmt.cpp:452 AITready Found : control[1], name[ZDF start], url[http://hbbtv.zdf.de/zdfstart/index.php] 13:52:53.9321 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF programm], url[http://hbbtv.zdf.de/zdfepg/index.php] 13:52:53.9322 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF mediathek], url[http://hbbtv.zdf.de/zdfm3/index.php] 13:52:53.9323 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF nachrichten], url[http://hbbtv.zdf.de/zdfnews/index.php] 13:52:53.9324 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDFtivi], url[http://hbbtv.zdf.de/zdfm3/index.php?tivi=1] 13:52:54.8998 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF start 13:52:54.9002 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfstart/index.php 13:52:54.9006 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:52:54.9008 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 1 13:52:54.9012 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 1 13:52:54.9016 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:52:54.9018 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:52:54.9020 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF programm 13:52:54.9023 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfepg/index.php 13:52:54.9026 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:52:54.9029 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 6 13:52:54.9032 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:52:54.9034 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:52:54.9038 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:52:54.9041 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF mediathek 13:52:54.9044 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfm3/index.php 13:52:54.9048 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:52:54.9051 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 2 13:52:54.9053 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:52:54.9057 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:52:54.9059 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:52:54.9061 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF nachrichten 13:52:54.9064 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfnews/index.php 13:52:54.9067 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:52:54.9070 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 7 13:52:54.9073 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:52:54.9076 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:52:54.9079 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:52:54.9082 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDFtivi 13:52:54.9085 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfm3/index.php?tivi=1 13:52:54.9089 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:52:54.9091 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 8 13:52:54.9094 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:52:54.9097 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:52:54.9099 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:52:54.9979 { } Plugins/Extensions/EnhancedMovieCenter/VlcPluginInterface.py:54 [VLC] Checking for buildin servicets ... no 13:52:55.0000 { E } Plugins/Extensions/EnhancedMovieCenter/VlcPluginInterface.py:59 [VLC] Checking for existing and usable servicets.so ... No module named servicets 13:52:55.0016 { } Plugins/Extensions/EnhancedMovieCenter/VlcPluginInterface.py:62 [VLC] Checking for usable gstreamer service ... yes 13:52:55.2435 { } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: Next trashcan cleanup in 668 minutes 13:52:55.2480 [ ] base/e2avahi.cpp:48 activated [Avahi] watch activated: 0x1 13:52:55.2483 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:55.2485 [ ] dvb/dvbtime.cpp:478 updateTime [eDVBLocalTimerHandler] Transponder time is 05.11.2017 13:52:54 13:52:55.2486 [ ] dvb/dvbtime.cpp:491 updateTime [eDVBLocalTimerHandler] diff is -1 13:52:55.2487 [ ] dvb/dvbtime.cpp:494 updateTime [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time 13:52:55.2487 [ ] dvb/dvbtime.cpp:49 setRTC [eDVBLocalTimerHandler] set RTC Time 13:52:55.2490 [ ] dvb/dvbtime.cpp:564 updateTime [eDVBLocalTimerHandler] update RTC 13:52:55.2490 [ ] dvb/dvbtime.cpp:584 updateTime [eDVBLocalTimerHandler] time update to 13:52:54 13:52:55.2491 [ ] dvb/dvbtime.cpp:587 updateTime [eDVBLocalTimerHandler] m_time_difference is -1 13:52:55.2492 [ ] dvb/dvbtime.cpp:591 updateTime [eDVBLocalTimerHandler] set Linux Time 13:52:54.2492 [ ] dvb/dvbtime.cpp:602 updateTime [eDVBLocalTimerHandler] time after update is 13:52:54 13:52:54.2501 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:52:54.2510 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:52:54.2600 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:52:54.2666 [ ] base/e2avahi.cpp:26 timeout [Avahi] timeout elapsed 13:52:54.2668 [ ] base/e2avahi.cpp:372 avahi_timeout_update [Avahi] avahi_timeout_update 13:52:54.3157 { } Components/AVSwitch.py:322 setWss [VideoMode] setting wss: auto 13:52:54.3166 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 13:52:54.3181 { } Components/AVSwitch.py:345 setPolicy169 [VideoMode] setting policy2: scale 13:52:54.3739 [ ] dvb/epgcache.cpp:1594 startEPG [EPGC] start caching events 13:52:55.7519 [ ] base/ioprio.cpp:91 setIoPrio setIoPrio best-effort level 7 ok 13:52:55.7521 [ ] components/file_eraser.cpp:88 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift/pts_livebuffer_1.meta.del' 13:52:55.7539 [ ] components/file_eraser.cpp:88 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift/pts_livebuffer_2.meta.del' 13:52:55.7552 [ ] components/file_eraser.cpp:88 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift/pts_livebuffer_2.del' 13:52:55.7638 [ ] service/servicedvb.cpp:2451 startTimeshift Start timeshift! 13:52:55.7646 [ ] service/servicedvb.cpp:2482 startTimeshift recording to /media/hdd/timeshift/timeshift.XpAcc4 13:52:55.7662 [ ] base/ioprio.cpp:91 setIoPrio setIoPrio realtime level 7 ok 13:52:55.7663 [ ] base/filepush.cpp:372 thread [eFilePushThreadRecorder] THREAD START 13:52:55.7665 [ ] dvb/demux.cpp:903 startPID add PID 6100(0x17d4) 13:52:55.7667 [ ] dvb/demux.cpp:903 startPID add PID 6110(0x17de) 13:52:55.7669 [ ] dvb/demux.cpp:903 startPID add PID 6120(0x17e8) 13:52:55.7670 [ ] dvb/demux.cpp:903 startPID add PID 6121(0x17e9) 13:52:55.7672 [ ] dvb/demux.cpp:903 startPID add PID 6122(0x17ea) 13:52:55.7673 [ ] dvb/demux.cpp:903 startPID add PID 6123(0x17eb) 13:52:55.7675 [ ] dvb/demux.cpp:903 startPID add PID 6130(0x17f2) 13:52:55.7677 [ ] dvb/demux.cpp:903 startPID add PID 6131(0x17f3) 13:52:55.7740 { } Components/Timeshift.py:1243 ptsCreateEITFile [TIMESHIFT] - 'eEPGCache' object has no attribute 'saveEventToFile' 13:52:55.7761 [ ] service/servicedvb.cpp:1582 unpause eDVBServicePlay::unpause 13:52:56.2461 { } Plugins/Extensions/EnhancedMovieCenter/EMCFileCache.py:214 delPathFromCache EMC delPathFromCache /media/hdd/movie/trashcan 13:52:56.2490 { } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: [EMCMS] trashcan cleanup: nothing to delete... 13:53:01.0835 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:53:01,082 - DEBUG - IBTS hide 13:53:01.0957 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:53:01,094 - DEBUG - IBTS timerHide 13:53:01.0976 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:53:01,096 - DEBUG - IBTS tunerHide 13:53:01.3785 [ ] dvb/epgcache.cpp:1957 abortNonAvail [EPGC] abort non avail schedule other reading 13:53:01.3791 [ ] dvb/epgcache.cpp:1981 abortNonAvail [EPGC] abort non avail netmed schedule reading 13:53:01.3800 [ ] dvb/epgcache.cpp:1988 abortNonAvail [EPGC] abort non avail netmed schedule other reading 13:53:01.3804 [ ] dvb/epgcache.cpp:1997 abortNonAvail [EPGC] abort non avail FreeSat schedule_other reading 13:53:01.3809 [ ] dvb/epgcache.cpp:2008 abortNonAvail [EPGC] abort non avail viasat reading 13:53:02.7541 < > dvb/epgcache.cpp:2269 readData [EPGC] schedule 13:53:02.7542 [ ] dvb/epgcache.cpp:2310 readData finished(1509886382) 13:53:08.8924 < > dvb/epgcache.cpp:2269 readData [EPGC] nownext 13:53:08.8925 [ ] dvb/epgcache.cpp:2310 readData finished(1509886388) 13:53:08.8931 [ ] dvb/epgcache.cpp:1573 finishEPG [EPGC] stop caching events 13:53:08.8932 [ ] dvb/epgcache.cpp:1575 finishEPG [EPGC] next update in 60 min 13:53:36.7766 [ ] components/file_eraser.cpp:88 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift/pts_livebuffer_1.sc.del' 13:53:36.7777 [ ] components/file_eraser.cpp:88 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift/pts_livebuffer_1.del' 13:54:20.4394 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:54:20.7484 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:54:20.7890 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:54:22.1001 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:54:22.5005 [ ] driver/rcinput.cpp:38 handleCode 2 77 1 13:54:22.6685 [ ] driver/rcinput.cpp:38 handleCode 2 77 1 13:54:22.7324 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:54:22.7610 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:54:24.3849 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:54:24.7884 [ ] driver/rcinput.cpp:38 handleCode 2 77 1 13:54:24.9084 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:54:24.9392 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:54:27.3406 [ ] driver/rcinput.cpp:38 handleCode 1 a8 1 13:54:27.3419 { } Components/ActionMap.py:46 action action -> InfobarTimeshiftActivateActions timeshiftActivateEnd 13:54:27.3426 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=-1, apid=-1 13:54:27.3427 < > dvb/decoder.cpp:723 stop DEMUX_STOP - pcr - ok 13:54:27.3428 < > dvb/decoder.cpp:425 stop DEMUX_STOP - video - ok 13:54:27.3429 < > dvb/decoder.cpp:434 stop VIDEO_STOP - ok 13:54:27.3441 < > dvb/decoder.cpp:159 stop AUDIO_STOP - ok 13:54:27.3752 < > dvb/decoder.cpp:167 stop DEMUX_STOP - audio - ok 13:54:27.3755 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:54:27.3756 < > dvb/decoder.cpp:800 stop DEMUX_STOP - ttx - ok 13:54:27.3851 [ ] dvb/pmt.cpp:1020 tuneExt alloc PVR 13:54:27.3853 [ ] dvb/dvb.cpp:1098 allocateDemux allocate demux 13:54:27.3855 [ ] dvb/tstools.cpp:96 setSource loading streaminfo for /media/hdd/timeshift/timeshift.XpAcc4 13:54:27.3856 [ E ] base/cfile.h:17 CFile error /media/hdd/timeshift/timeshift.XpAcc4.ap [No such file or directory] 13:54:27.3866 [ ] base/ioprio.cpp:91 setIoPrio setIoPrio best-effort level 0 ok 13:54:27.3867 [ ] dvb/pmt.cpp:77 channelStateChanged ok ... now we start!! 13:54:27.3868 [ ] base/filepush.cpp:60 thread FILEPUSH THREAD START 13:54:27.3868 [ ] service/servicedvb.cpp:1235 serviceEventTimeshift eventNewProgramInfo TS 13:54:27.3869 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 1 audio stream(s) (17e8), and the pcr pid is 17de, and the text pid is 17f2 13:54:27.3940 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:54:27.3945 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:54:27.3947 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:54:27.3950 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0x17e8) - audio - ok 13:54:27.3952 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 13:54:27.3955 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 13:54:27.3956 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:54:27.3957 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 13:54:27.4038 [ ] dvb/decoder.cpp:267 eDVBVideo Video Device: /dev/dvb/adapter0/video0 13:54:27.4039 [ ] dvb/decoder.cpp:274 eDVBVideo demux device: /dev/dvb/adapter0/demux7 13:54:27.4040 < > dvb/decoder.cpp:354 startPid VIDEO_SET_STREAMTYPE 1 - ok 13:54:27.4041 < > dvb/decoder.cpp:389 startPid DMX_SET_PES_FILTER(0x17de) - video - ok 13:54:27.4042 < > dvb/decoder.cpp:397 startPid DEMUX_START - video - ok 13:54:27.4043 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 13:54:27.4045 < > dvb/decoder.cpp:412 startPid VIDEO_PLAY - ok 13:54:27.4099 < > dvb/decoder.cpp:779 startPid DMX_SET_PES_FILTER(0x17f2) - ttx - ok 13:54:27.4100 < > dvb/decoder.cpp:787 startPid DEMUX_START - ttx - ok 13:54:27.4149 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:27.4151 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:54:27.4151 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:54:27.4152 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:54:27.4207 [ ] dvb/teletext.cpp:640 setPageAndMagazine disable teletext subtitles page ffffffffffffffff (und) 13:54:27.4327 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 13:54:27.4331 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 18 13:54:27.4334 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 13:54:27.4352 [ ] service/servicedvb.cpp:2817 switchToTimeshift eDVBServicePlay::switchToTimeshift, in pause mode now. 13:54:27.4353 [ ] service/servicedvb.cpp:1569 pause eDVBServicePlay::pause 13:54:27.4353 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=6110, apid=6120 13:54:27.4354 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:27.4355 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:54:27.4356 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - 13:54:27.4446 [ ] dvb/dvb.cpp:2147 getNextSourceSpan seeking relative to end. len=8251200, seek = -1000 13:54:27.4447 [ ] dvb/tstools.cpp:651 takeSamples samples step 41942988, pts begin 5456474852, pts end 5464726052, offs begin 12056, offs end 177254328: 13:54:27.4449 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=12056 pts=5456474852 13:54:27.4450 [ ] dvb/tstools.cpp:694 takeSample adding sample 12056: pts 0 -> pos 12056 (diff 0 bytes) 13:54:27.4451 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=41880784 pts=5458436852 13:54:27.4452 [ ] dvb/tstools.cpp:694 takeSample adding sample 41955044: pts 1962000 -> pos 41880784 (diff -74260 bytes) 13:54:27.4453 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=79219276 pts=5460168452 13:54:27.4454 [ ] dvb/tstools.cpp:694 takeSample adding sample 83898032: pts 3693600 -> pos 79219276 (diff -4678756 bytes) 13:54:27.4456 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=125830492 pts=5462326652 13:54:27.4457 [ ] dvb/tstools.cpp:694 takeSample adding sample 125841020: pts 5851800 -> pos 125830492 (diff -10528 bytes) 13:54:27.4458 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=167767088 pts=5464279652 13:54:27.4459 [ ] dvb/tstools.cpp:694 takeSample adding sample 167784008: pts 7804800 -> pos 167767088 (diff -16920 bytes) 13:54:27.4459 [ ] dvb/tstools.cpp:411 getOffset using: 7804800:8251200 -> 167767088:177254328 13:54:27.4461 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=168398204 pts=5464306652 13:54:27.4462 [ ] dvb/tstools.cpp:694 takeSample adding sample 177233052: pts 7831800 -> pos 168398204 (diff -8834848 bytes) 13:54:27.4462 [ ] dvb/tstools.cpp:444 getOffset calculated diff -4648 ms 13:54:27.4463 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:54:27.4464 [ ] dvb/tstools.cpp:411 getOffset using: 7831800:8251200 -> 168398204:177254328 13:54:27.4465 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=168398204 pts=5464306652 13:54:27.4466 [ ] dvb/tstools.cpp:694 takeSample adding sample 177233052: pts 7831800 -> pos 168398204 (diff -8834848 bytes) 13:54:27.4467 [ ] dvb/tstools.cpp:444 getOffset calculated diff -4648 ms 13:54:27.4467 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:54:27.4468 [ ] dvb/tstools.cpp:411 getOffset using: 7831800:8251200 -> 168398204:177254328 13:54:27.4468 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=168398204 pts=5464306652 13:54:27.4469 [ ] dvb/tstools.cpp:694 takeSample adding sample 177233052: pts 7831800 -> pos 168398204 (diff -8834848 bytes) 13:54:27.4470 [ ] dvb/tstools.cpp:444 getOffset calculated diff -4648 ms 13:54:27.4470 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:54:27.4470 [ ] dvb/tstools.cpp:411 getOffset using: 7831800:8251200 -> 168398204:177254328 13:54:27.4471 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=168398204 pts=5464306652 13:54:27.4472 [ ] dvb/tstools.cpp:694 takeSample adding sample 177233052: pts 7831800 -> pos 168398204 (diff -8834848 bytes) 13:54:27.4472 [ ] dvb/tstools.cpp:444 getOffset calculated diff -4648 ms 13:54:27.4473 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:54:27.4473 [ ] dvb/tstools.cpp:411 getOffset using: 7831800:8251200 -> 168398204:177254328 13:54:27.4474 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=168398204 pts=5464306652 13:54:27.4475 [ ] dvb/tstools.cpp:694 takeSample adding sample 177233052: pts 7831800 -> pos 168398204 (diff -8834848 bytes) 13:54:27.4476 [ ] dvb/tstools.cpp:444 getOffset calculated diff -4648 ms 13:54:27.4476 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:54:27.4476 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 177233052 as offset for 7831800 13:54:27.4477 [ ] dvb/dvb.cpp:2193 getNextSourceSpan ok, resolved skip (rel: 0, diff 7831800), now at 0a905c9c ok 13:54:27.4537 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:54:27.4580 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 1 audio stream(s) (17e8), and the pcr pid is 17de, and the text pid is 17f2 13:54:27.4586 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:54:27.4593 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:54:27.4595 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=6110, apid=6120 13:54:27.4622 [ ] service/servicedvb.cpp:1569 pause eDVBServicePlay::pause 13:54:27.5135 [ ] service/servicedvb.cpp:1595 seekTo eDVBServicePlay::seekTo: jump -90000 13:54:27.5136 [ ] dvb/dvb.cpp:1942 cueSheetEvent seek. 13:54:27.5139 [ ] base/filepush.cpp:177 thread wait for driver eof aborted by signal 13:54:27.5141 [ ] base/filepush.cpp:306 pause FILEPUSH waiting for pause 13:54:27.5142 [ ] base/filepush.cpp:259 thread FILEPUSH THREAD PAUSED 13:54:27.5144 < > dvb/decoder.cpp:179 flush AUDIO_CLEAR_BUFFER - ok 13:54:27.5466 < > dvb/decoder.cpp:446 flush VIDEO_CLEAR_BUFFER - ok 13:54:27.5548 [ ] dvb/dvb.cpp:2147 getNextSourceSpan seeking relative to end. len=8258400, seek = -90000 13:54:27.5549 [ ] dvb/tstools.cpp:411 getOffset using: 7831800:8251200 -> 168398204:177254328 13:54:27.5550 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=175497460 pts=5464636052 13:54:27.5550 [ ] dvb/tstools.cpp:694 takeSample adding sample 175505896: pts 8161200 -> pos 175497460 (diff -8436 bytes) 13:54:27.5551 [ ] dvb/tstools.cpp:444 getOffset calculated diff -80 ms 13:54:27.5551 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 175505896 as offset for 8161200 13:54:27.5552 [ ] dvb/dvb.cpp:2193 getNextSourceSpan ok, resolved skip (rel: 0, diff 8161200), now at 0a7601e8 13:54:27.5578 [ ] driver/rcinput.cpp:38 handleCode 0 a8 1 13:54:27.6004 [ ] dvb/dvb.cpp:1928 pvrEvent eDVBChannel: pvrEvent evtStopped 13:54:27.6014 [ ] dvb/pmt.cpp:202 PATready PATready 13:54:27.6015 [ ] dvb/pmt.cpp:213 PATready PAT TSID: 0x03f3 (1011) 13:54:27.6017 [ ] dvb/pmt.cpp:242 PATready use pmtpid 17d4 for service_id 2b66 13:54:27.6237 [ ] service/servicedvb.cpp:1235 serviceEventTimeshift eventNewProgramInfo TS 13:54:27.6278 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 4 audio stream(s) (17e8, 17e9, 17ea, 17eb), and the pcr pid is 17de, and the text pid is 17f2 13:54:27.6288 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:54:27.6293 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:54:27.6295 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=6110, apid=6120 13:54:27.6541 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 13:54:27.6552 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 18 13:54:27.6559 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 13:54:27.6962 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:54:27.6979 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:54:27.7063 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:54:27.7643 { } Components/AVSwitch.py:322 setWss [VideoMode] setting wss: auto 13:54:27.7657 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 13:54:27.7678 { } Components/AVSwitch.py:345 setPolicy169 [VideoMode] setting policy2: scale 13:54:27.8888 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 13:54:28.0589 [ ] service/servicedvb.cpp:1476 setFastForward eDVBServicePlay::setFastForward(-2) 13:54:28.0590 [ ] service/servicedvb.cpp:1506 setFastForward_internal setting cue skipmode to -2 13:54:28.0591 [ ] dvb/dvb.cpp:1957 cueSheetEvent skipmode ratio is -180000:90000, bitrate is 15461370 bit/s 13:54:28.0591 [ ] dvb/dvb.cpp:1967 cueSheetEvent resolved to: -996788 524288 13:54:28.0592 [ ] dvb/dvb.cpp:1985 cueSheetEvent flush pvr 13:54:28.0593 [ ] base/filepush.cpp:177 thread wait for driver eof aborted by signal 13:54:28.0595 [ ] base/filepush.cpp:306 pause FILEPUSH waiting for pause 13:54:28.0596 [ ] base/filepush.cpp:259 thread FILEPUSH THREAD PAUSED 13:54:28.0597 < > dvb/decoder.cpp:179 flush AUDIO_CLEAR_BUFFER - ok 13:54:28.0962 < > dvb/decoder.cpp:446 flush VIDEO_CLEAR_BUFFER - ok 13:54:28.1051 [ ] dvb/tstools.cpp:411 getOffset using: 8161200:8251200 -> 175497460:177254328 13:54:28.1053 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=176074064 pts=5464668452 13:54:28.1054 [ ] dvb/dvb.cpp:1987 cueSheetEvent done 13:54:28.1055 [ ] dvb/tstools.cpp:694 takeSample adding sample 176165024: pts 8193600 -> pos 176074064 (diff -90960 bytes) 13:54:28.1056 [ ] dvb/decoder.cpp:829 setState decoder state: trickmode, vpid=6110, apid=6120 13:54:28.1056 [ ] dvb/tstools.cpp:444 getOffset calculated diff -20 ms 13:54:28.1056 < > dvb/decoder.cpp:159 stop AUDIO_STOP - 13:54:28.1057 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 176165024 as offset for 8193600 13:54:28.1057 [ ] dvb/dvb.cpp:2193 getNextSourceSpan ok, resolved skip (rel: 1, diff 8193600), now at 0a8010a0 ok 13:54:28.1349 < > dvb/decoder.cpp:167 stop DEMUX_STOP - audio - ok 13:54:28.1354 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:54:28.1357 < > dvb/decoder.cpp:800 stop DEMUX_STOP - ttx - ok 13:54:28.1388 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:28.1390 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(1) - ok 13:54:28.1392 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:54:28.1738 [ ] dvb/dvb.cpp:1928 pvrEvent eDVBChannel: pvrEvent evtStopped 13:54:29.6804 [ ] driver/rcinput.cpp:38 handleCode 1 cf 1 13:54:29.6819 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS playpauseService 13:54:29.6838 [ ] service/servicedvb.cpp:1582 unpause eDVBServicePlay::unpause 13:54:29.6839 [ ] service/servicedvb.cpp:1506 setFastForward_internal setting cue skipmode to 0 13:54:29.6840 [ ] dvb/dvb.cpp:1976 cueSheetEvent skipmode ratio is 0, normal play 13:54:29.6841 [ ] dvb/dvb.cpp:1985 cueSheetEvent flush pvr 13:54:29.6842 [ ] base/filepush.cpp:306 pause FILEPUSH waiting for pause 13:54:29.6842 [ ] base/filepush.cpp:259 thread FILEPUSH THREAD PAUSED 13:54:29.6843 < > dvb/decoder.cpp:446 flush VIDEO_CLEAR_BUFFER - ok 13:54:29.6931 [ ] dvb/tstools.cpp:411 getOffset using: 5851800:7804800 -> 125830492:167767088 13:54:29.6938 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=154903760 pts=5463682052 13:54:29.6940 [ ] dvb/tstools.cpp:694 takeSample adding sample 154934748: pts 7207200 -> pos 154903760 (diff -30988 bytes) 13:54:29.6942 [ ] dvb/tstools.cpp:444 getOffset calculated diff 0 ms 13:54:29.6943 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 154934748 as offset for 7207200 13:54:29.6944 [ ] dvb/dvb.cpp:2193 getNextSourceSpan ok, resolved skip (rel: 1, diff 7207200), now at 093c1ddc 13:54:29.6964 [ ] dvb/dvb.cpp:1987 cueSheetEvent done 13:54:29.6969 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:54:29.6973 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0x17e8) - audio - ok 13:54:29.6977 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 13:54:29.6980 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 13:54:29.6983 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:54:29.6989 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 13:54:29.7047 < > dvb/decoder.cpp:779 startPid DMX_SET_PES_FILTER(0x17f2) - ttx - ok 13:54:29.7049 < > dvb/decoder.cpp:787 startPid DEMUX_START - ttx - ok 13:54:29.7055 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:29.7057 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:54:29.7061 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:54:29.7187 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:54:29.7293 [ ] dvb/dvb.cpp:1928 pvrEvent eDVBChannel: pvrEvent evtStopped 13:54:29.8375 [ ] driver/rcinput.cpp:38 handleCode 0 cf 1 13:54:29.8669 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:54:35.0293 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:54:35,028 - DEBUG - IBTS hide 13:54:35.0410 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:54:35,040 - DEBUG - IBTS timerHide 13:54:35.0429 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:54:35,041 - DEBUG - IBTS tunerHide 13:54:35.9838 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:54:35.9852 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS pauseService 13:54:35.9860 [ ] service/servicedvb.cpp:1569 pause eDVBServicePlay::pause 13:54:35.9861 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=6110, apid=6120 13:54:35.9862 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:35.9863 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:54:35.9864 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 13:54:35.9865 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:54:36.1885 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:54:36.2170 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:54:39.3235 [ ] driver/rcinput.cpp:38 handleCode 1 cf 1 13:54:39.3249 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS playpauseService 13:54:39.3270 [ ] service/servicedvb.cpp:1582 unpause eDVBServicePlay::unpause 13:54:39.3271 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:54:39.3271 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:39.3272 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:54:39.3273 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:54:39.3275 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:54:39.5245 [ ] driver/rcinput.cpp:38 handleCode 0 cf 1 13:54:39.5516 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:54:39.6355 [ ] dvb/pmt.cpp:288 AITready AITready 13:54:41.3536 [ ] driver/rcinput.cpp:38 handleCode 1 d0 1 13:54:41.5572 [ ] driver/rcinput.cpp:38 handleCode 0 d0 1 13:54:41.5879 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS seekFwd 13:54:41.5895 [ ] service/servicedvb.cpp:1476 setFastForward eDVBServicePlay::setFastForward(2) 13:54:41.5896 [ ] dvb/decoder.cpp:829 setState decoder state: decoderfastforward, vpid=6110, apid=6120 13:54:41.5897 < > dvb/decoder.cpp:159 stop AUDIO_STOP - ok 13:54:41.6218 < > dvb/decoder.cpp:167 stop DEMUX_STOP - audio - ok 13:54:41.6222 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:54:41.6225 < > dvb/decoder.cpp:800 stop DEMUX_STOP - ttx - ok 13:54:41.6230 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:41.6231 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(2) - ok 13:54:41.6232 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:54:42.4586 [ ] driver/rcinput.cpp:38 handleCode 1 d0 1 13:54:42.6606 [ ] driver/rcinput.cpp:38 handleCode 0 d0 1 13:54:42.6908 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS seekFwd 13:54:42.6923 [ ] service/servicedvb.cpp:1476 setFastForward eDVBServicePlay::setFastForward(4) 13:54:42.6925 [ ] dvb/decoder.cpp:829 setState decoder state: decoderfastforward, vpid=6110, apid=6120 13:54:42.6925 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:42.6926 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(4) - ok 13:54:42.6927 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:54:44.8568 [ ] driver/rcinput.cpp:38 handleCode 1 d0 1 13:54:45.0605 [ ] driver/rcinput.cpp:38 handleCode 0 d0 1 13:54:45.1035 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS seekFwd 13:54:45.1053 [ ] service/servicedvb.cpp:1476 setFastForward eDVBServicePlay::setFastForward(8) 13:54:45.1055 [ ] dvb/decoder.cpp:829 setState decoder state: decoderfastforward, vpid=6110, apid=6120 13:54:45.1056 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:45.1057 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(8) - ok 13:54:45.1058 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:54:47.2250 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 13:54:47.4781 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 13:54:47.5542 [ ] base/filepush.cpp:174 thread wait for driver eof ok 13:54:47.5544 [ ] base/filepush.cpp:202 thread reached EOF, but the file may grow. delaying 1 second. 13:54:47.5555 [ ] dvb/dvb.cpp:1920 pvrEvent eDVBChannel: End of file! 13:54:47.5556 [ ] service/servicedvb.cpp:1295 serviceEventTimeshift timeshift EOF, so let's go live 13:54:47.5557 [ ] service/servicedvb.cpp:2742 switchToLive SwitchToLive 13:54:47.5561 [ ] base/filepush.cpp:286 stop eFilePushThread stopping thread 13:54:47.5563 [ ] base/filepush.cpp:267 thread FILEPUSH THREAD STOP 13:54:47.6923 [ ] dvb/decoder.cpp:829 setState decoder state: decoderfastforward, vpid=-1, apid=-1 13:54:47.6925 < > dvb/decoder.cpp:425 stop DEMUX_STOP - video - ok 13:54:47.6926 < > dvb/decoder.cpp:434 stop VIDEO_STOP - ok 13:54:47.7041 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 4 audio stream(s) (17e8, 17e9, 17ea, 17eb), and the pcr pid is 17de, and the text pid is 17f2 13:54:47.7102 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:54:47.7107 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:54:47.7108 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:54:47.7110 < > dvb/decoder.cpp:702 startPid DMX_SET_PES_FILTER(0x17de) - pcr - ok 13:54:47.7110 < > dvb/decoder.cpp:710 startPid DEMUX_START - pcr - ok 13:54:47.7112 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0x17e8) - audio - ok 13:54:47.7113 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 13:54:47.7115 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 13:54:47.7115 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:54:47.7116 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 13:54:47.7175 [ ] dvb/decoder.cpp:267 eDVBVideo Video Device: /dev/dvb/adapter0/video0 13:54:47.7176 [ ] dvb/decoder.cpp:274 eDVBVideo demux device: /dev/dvb/adapter0/demux0 13:54:47.7177 < > dvb/decoder.cpp:354 startPid VIDEO_SET_STREAMTYPE 1 - ok 13:54:47.7178 < > dvb/decoder.cpp:389 startPid DMX_SET_PES_FILTER(0x17de) - video - ok 13:54:47.7178 < > dvb/decoder.cpp:397 startPid DEMUX_START - video - ok 13:54:47.7180 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 13:54:47.7180 < > dvb/decoder.cpp:412 startPid VIDEO_PLAY - ok 13:54:47.7235 < > dvb/decoder.cpp:779 startPid DMX_SET_PES_FILTER(0x17f2) - ttx - ok 13:54:47.7236 < > dvb/decoder.cpp:787 startPid DEMUX_START - ttx - ok 13:54:47.7280 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:54:47.7282 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:54:47.7283 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:54:47.7284 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:54:47.7285 [ ] dvb/teletext.cpp:640 setPageAndMagazine disable teletext subtitles page ffffffffffffffff (und) 13:54:47.7307 [ ] service/servicedvb.cpp:1582 unpause eDVBServicePlay::unpause 13:54:48.4383 [ ] dvb/pmt.cpp:288 AITready AITready 13:54:48.4385 [ ] dvb/pmt.cpp:307 AITready Section Length : 432, Total Section Length : 435 13:54:48.4386 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 1 13:54:48.4388 [ ] dvb/pmt.cpp:282 saveData Save Data Len : [435] 13:54:48.4389 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 6 13:54:48.4390 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 2 13:54:48.4391 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 7 13:54:48.4391 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 8 13:54:48.4392 [ ] dvb/pmt.cpp:452 AITready Found : control[1], name[ZDF start], url[http://hbbtv.zdf.de/zdfstart/index.php] 13:54:48.4393 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF programm], url[http://hbbtv.zdf.de/zdfepg/index.php] 13:54:48.4394 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF mediathek], url[http://hbbtv.zdf.de/zdfm3/index.php] 13:54:48.4395 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF nachrichten], url[http://hbbtv.zdf.de/zdfnews/index.php] 13:54:48.4395 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDFtivi], url[http://hbbtv.zdf.de/zdfm3/index.php?tivi=1] 13:54:49.3794 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF start 13:54:49.3798 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfstart/index.php 13:54:49.3801 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:54:49.3805 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 1 13:54:49.3808 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 1 13:54:49.3812 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:54:49.3815 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:54:49.3818 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF programm 13:54:49.3821 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfepg/index.php 13:54:49.3825 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:54:49.3828 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 6 13:54:49.3831 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:54:49.3834 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:54:49.3837 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:54:49.3840 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF mediathek 13:54:49.3843 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfm3/index.php 13:54:49.3846 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:54:49.3849 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 2 13:54:49.3854 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:54:49.3858 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:54:49.3860 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:54:49.3862 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF nachrichten 13:54:49.3866 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfnews/index.php 13:54:49.3869 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:54:49.3871 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 7 13:54:49.3875 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:54:49.3878 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:54:49.3879 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:54:49.3883 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDFtivi 13:54:49.3887 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfm3/index.php?tivi=1 13:54:49.3891 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:54:49.3895 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 8 13:54:49.3898 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:54:49.3901 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:54:49.3903 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:54:49.4035 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:54:49.4046 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:54:49.4117 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:54:49.4661 { } Components/AVSwitch.py:322 setWss [VideoMode] setting wss: auto 13:54:49.4668 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 13:54:49.4682 { } Components/AVSwitch.py:345 setPolicy169 [VideoMode] setting policy2: scale 13:54:51.9660 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:54:51.9678 { } Components/ActionMap.py:46 action action -> InfobarTimeshiftSeekPointerActions SeekPointerPlay 13:54:51.9681 { W } Components/ActionMap.py:53 action unknown action InfobarTimeshiftSeekPointerActions/SeekPointerPlay! typo in keymap? 13:54:52.1735 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:54:52.2099 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:54:53.0378 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:54:53,036 - DEBUG - IBTS hide 13:54:53.0496 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:54:53,048 - DEBUG - IBTS timerHide 13:54:53.0513 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:54:53,050 - DEBUG - IBTS tunerHide 13:54:54.1601 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:54:54.4684 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:54:54.5009 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:55:02.7480 [ ] driver/rcinput.cpp:38 handleCode 1 8b 1 13:55:02.9494 [ ] driver/rcinput.cpp:38 handleCode 0 8b 1 13:55:03.0025 { } skin.py:1313 readSkin [SKIN] processing screen menu_mainmenu: 13:55:03.0847 { } skin.py:1313 readSkin [SKIN] processing screen MenuSummary: 13:55:04.2141 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:04.4205 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:04.5341 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:04.8444 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:05.3140 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:05.4695 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:05.9083 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:06.1095 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:07.2341 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:07.4377 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:07.6401 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:07.6413 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 13:55:07.6531 { } skin.py:1313 readSkin [SKIN] processing screen menu_setup: 13:55:07.6921 { } skin.py:1313 readSkin [SKIN] processing screen MenuSummary: 13:55:07.8445 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:08.7306 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:08.9325 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:09.4978 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:09.7012 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:09.8472 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:10.0045 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:10.2066 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:10.3645 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:10.4780 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:10.6295 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:11.7770 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:11.9814 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:12.1263 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:12.2765 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:12.6016 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:12.6027 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 13:55:12.7363 { } skin.py:1313 readSkin [SKIN] processing screen menu_system: 13:55:12.7723 { } skin.py:1313 readSkin [SKIN] processing screen MenuSummary: 13:55:12.8045 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:13.5086 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:13.7094 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:13.9444 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:13.9455 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 13:55:13.9986 { } skin.py:1313 readSkin [SKIN] processing screen Menu: 13:55:14.0366 { } skin.py:1313 readSkin [SKIN] processing screen MenuSummary: 13:55:14.1495 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:14.6459 [ ] driver/rcinput.cpp:38 handleCode 1 6c 1 13:55:14.8524 [ ] driver/rcinput.cpp:38 handleCode 0 6c 1 13:55:14.9642 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:14.9654 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 13:55:14.9817 { } skin.py:1313 readSkin [SKIN] processing screen LogManager: 13:55:15.0178 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element LogsSize in 13:55:15.1037 { } skin.py:1313 readSkin [SKIN] processing screen PluginBrowserSummary: 13:55:15.1196 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:18.1681 [ ] driver/rcinput.cpp:38 handleCode 1 18f 1 13:55:18.3244 [ ] driver/rcinput.cpp:38 handleCode 0 18f 1 13:55:18.3255 { } Components/ActionMap.py:46 action action -> ColorActions green 13:55:21.4847 [ ] driver/rcinput.cpp:38 handleCode 1 ae 1 13:55:21.4860 { } Components/ActionMap.py:46 action action -> OkCancelActions cancel 13:55:21.6844 [ ] driver/rcinput.cpp:38 handleCode 0 ae 1 13:55:23.3229 [ ] driver/rcinput.cpp:38 handleCode 1 67 1 13:55:23.5244 [ ] driver/rcinput.cpp:38 handleCode 0 67 1 13:55:23.8174 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:23.8186 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 13:55:23.9150 { } skin.py:1313 readSkin [SKIN] processing screen Setup: 13:55:23.9202 { E } skin.py:1266 process [SKIN] SKIN ERROR in screen 'Setup' widget 'widget': {XionHDF/skin.xml}: source 'key_yellow' was not found in screen 'Setup'!. Please contact the skin's author! 13:55:23.9210 { E } skin.py:1266 process [SKIN] SKIN ERROR in screen 'Setup' widget 'widget': {XionHDF/skin.xml}: source 'key_blue' was not found in screen 'Setup'!. Please contact the skin's author! 13:55:23.9407 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element HelpWindow in 13:55:23.9416 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element description in 13:55:24.0278 { } skin.py:1313 readSkin [SKIN] processing screen SetupSummary: 13:55:24.0400 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:30.8975 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:30.8988 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:30.8991 { W } Components/ActionMap.py:53 action unknown action SetupActions/ok! typo in keymap? 13:55:30.8995 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:31.1006 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:32.5957 [ ] driver/rcinput.cpp:38 handleCode 1 6a 1 13:55:32.5972 { } Components/ActionMap.py:46 action action -> SetupActions right 13:55:32.5976 { W } Components/ActionMap.py:53 action unknown action SetupActions/right! typo in keymap? 13:55:32.5979 { } Components/ActionMap.py:46 action action -> SetupActions right 13:55:32.7965 [ ] driver/rcinput.cpp:38 handleCode 0 6a 1 13:55:34.5840 [ ] driver/rcinput.cpp:38 handleCode 1 69 1 13:55:34.5852 { } Components/ActionMap.py:46 action action -> SetupActions left 13:55:34.5856 { W } Components/ActionMap.py:53 action unknown action SetupActions/left! typo in keymap? 13:55:34.5859 { } Components/ActionMap.py:46 action action -> SetupActions left 13:55:34.7894 [ ] driver/rcinput.cpp:38 handleCode 0 69 1 13:55:35.7208 [ ] driver/rcinput.cpp:38 handleCode 1 69 1 13:55:35.7220 { } Components/ActionMap.py:46 action action -> SetupActions left 13:55:35.7224 { W } Components/ActionMap.py:53 action unknown action SetupActions/left! typo in keymap? 13:55:35.7227 { } Components/ActionMap.py:46 action action -> SetupActions left 13:55:35.8775 [ ] driver/rcinput.cpp:38 handleCode 0 69 1 13:55:36.9298 [ ] driver/rcinput.cpp:38 handleCode 1 6a 1 13:55:36.9311 { } Components/ActionMap.py:46 action action -> SetupActions right 13:55:36.9314 { W } Components/ActionMap.py:53 action unknown action SetupActions/right! typo in keymap? 13:55:36.9317 { } Components/ActionMap.py:46 action action -> SetupActions right 13:55:37.1324 [ ] driver/rcinput.cpp:38 handleCode 0 6a 1 13:55:42.2384 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:42.2398 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:42.2401 { W } Components/ActionMap.py:53 action unknown action SetupActions/ok! typo in keymap? 13:55:42.2405 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:42.4454 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:43.2319 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:43.2331 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:43.2334 { W } Components/ActionMap.py:53 action unknown action SetupActions/ok! typo in keymap? 13:55:43.2338 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:43.4365 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:43.4709 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:43.4721 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:43.4725 { W } Components/ActionMap.py:53 action unknown action SetupActions/ok! typo in keymap? 13:55:43.4730 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:43.6284 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:45.1165 [ ] driver/rcinput.cpp:38 handleCode 1 69 1 13:55:45.1179 { } Components/ActionMap.py:46 action action -> SetupActions left 13:55:45.1182 { W } Components/ActionMap.py:53 action unknown action SetupActions/left! typo in keymap? 13:55:45.1185 { } Components/ActionMap.py:46 action action -> SetupActions left 13:55:45.3176 [ ] driver/rcinput.cpp:38 handleCode 0 69 1 13:55:47.4598 [ ] driver/rcinput.cpp:38 handleCode 1 160 1 13:55:47.4609 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:47.4612 { W } Components/ActionMap.py:53 action unknown action SetupActions/ok! typo in keymap? 13:55:47.4616 { } Components/ActionMap.py:46 action action -> SetupActions ok 13:55:47.6420 { } Plugins/Extensions/AutoTimer/AutoPoller.py:35 query [AutoTimer][query] current auto poll 2017-11-05 13:55:47 13:55:47.6428 { } Plugins/Extensions/AutoTimer/AutoPoller.py:52 query [AutoTimer] Auto Poll Started 13:55:47.6667 [ E ] dvb/epgcache.cpp:2555 lookupEventId [EPGC] event 1138 not found in epgcache 13:55:47.6670 [ E ] dvb/epgcache.cpp:2555 lookupEventId [EPGC] event 4018 not found in epgcache 13:55:47.6672 [ E ] dvb/epgcache.cpp:2555 lookupEventId [EPGC] event 4f54 not found in epgcache 13:55:47.6675 [ E ] dvb/epgcache.cpp:2555 lookupEventId [EPGC] event 76a4 not found in epgcache 13:55:47.6677 [ E ] dvb/epgcache.cpp:2555 lookupEventId [EPGC] event bca4 not found in epgcache 13:55:47.6679 [ E ] dvb/epgcache.cpp:2555 lookupEventId [EPGC] event cab4 not found in epgcache 13:55:47.6841 { } Plugins/Extensions/AutoTimer/AutoPoller.py:62 query [AutoTimer][query] next auto poll at 2017-11-10 13:55:47 13:55:47.6884 [ ] dvb/epgcache.cpp:3526 search [EPGC] lookup events with 'Club der roten Bänder' in title (ignore case) 13:55:47.6886 [ ] driver/rcinput.cpp:38 handleCode 0 160 1 13:55:48.3098 [ ] dvb/epgcache.cpp:3526 search [EPGC] lookup events with 'Der Bergdoktor' in title (ignore case) 13:55:49.0175 [ ] dvb/epgcache.cpp:3526 search [EPGC] lookup events with 'Hubert und Staller' in title (ignore case) 13:55:49.6455 [ ] dvb/epgcache.cpp:3526 search [EPGC] lookup events with 'MacGyver' in title (ignore case) 13:55:50.1242 [ ] driver/rcinput.cpp:38 handleCode 1 18f 1 13:55:50.1254 { } Components/ActionMap.py:46 action action -> SetupActions save 13:55:50.2550 [ ] dvb/epgcache.cpp:3526 search [EPGC] lookup events with 'Outlander' in title (ignore case) 13:55:50.3777 [ ] driver/rcinput.cpp:38 handleCode 0 18f 1 13:55:50.8837 [ ] dvb/epgcache.cpp:3526 search [EPGC] lookup events with 'Quantico' in title (ignore case) 13:55:51.4834 [ ] dvb/epgcache.cpp:3526 search [EPGC] lookup events with 'The 100' in title (ignore case) 13:55:52.0487 [ ] dvb/epgcache.cpp:3526 search [EPGC] lookup events with 'Vikings' in title (ignore case) 13:55:52.6632 { } Components/Task.py:375 jobDone job Components.Task.Job name=AutoTimer #tasks=9 completed with [] in None 13:55:52.7245 [ ] driver/rcinput.cpp:38 handleCode 1 ae 1 13:55:52.7257 { } Components/ActionMap.py:46 action action -> OkCancelActions cancel 13:55:52.9245 [ ] driver/rcinput.cpp:38 handleCode 0 ae 1 13:55:53.4467 [ ] driver/rcinput.cpp:38 handleCode 1 ae 1 13:55:53.4479 { } Components/ActionMap.py:46 action action -> OkCancelActions cancel 13:55:53.6525 [ ] driver/rcinput.cpp:38 handleCode 0 ae 1 13:55:53.9928 [ ] driver/rcinput.cpp:38 handleCode 1 ae 1 13:55:53.9940 { } Components/ActionMap.py:46 action action -> OkCancelActions cancel 13:55:54.1484 [ ] driver/rcinput.cpp:38 handleCode 0 ae 1 13:55:54.6661 [ ] driver/rcinput.cpp:38 handleCode 1 ae 1 13:55:54.6673 { } Components/ActionMap.py:46 action action -> OkCancelActions cancel 13:55:54.8684 [ ] driver/rcinput.cpp:38 handleCode 0 ae 1 13:55:59.3721 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:55:59.5734 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:55:59.6040 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:56:02.2642 [ ] driver/rcinput.cpp:38 handleCode 1 a8 1 13:56:02.2657 { } Components/ActionMap.py:46 action action -> InfobarTimeshiftActivateActions timeshiftActivateEnd 13:56:02.2664 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=-1, apid=-1 13:56:02.2665 < > dvb/decoder.cpp:723 stop DEMUX_STOP - pcr - ok 13:56:02.2666 < > dvb/decoder.cpp:425 stop DEMUX_STOP - video - ok 13:56:02.2667 < > dvb/decoder.cpp:434 stop VIDEO_STOP - ok 13:56:02.2679 < > dvb/decoder.cpp:159 stop AUDIO_STOP - ok 13:56:02.2968 < > dvb/decoder.cpp:167 stop DEMUX_STOP - audio - ok 13:56:02.2969 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:56:02.2970 < > dvb/decoder.cpp:800 stop DEMUX_STOP - ttx - ok 13:56:02.3045 [ ] dvb/pmt.cpp:1020 tuneExt alloc PVR 13:56:02.3053 [ ] dvb/dvb.cpp:1098 allocateDemux allocate demux 13:56:02.3055 [ ] dvb/tstools.cpp:96 setSource loading streaminfo for /media/hdd/timeshift/timeshift.XpAcc4 13:56:02.3057 [ E ] base/cfile.h:17 CFile error /media/hdd/timeshift/timeshift.XpAcc4.ap [No such file or directory] 13:56:02.3066 [ ] base/ioprio.cpp:91 setIoPrio setIoPrio best-effort level 0 ok 13:56:02.3066 [ ] dvb/pmt.cpp:77 channelStateChanged ok ... now we start!! 13:56:02.3067 [ ] base/filepush.cpp:60 thread FILEPUSH THREAD START 13:56:02.3068 [ ] service/servicedvb.cpp:1235 serviceEventTimeshift eventNewProgramInfo TS 13:56:02.3068 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 1 audio stream(s) (17e8 13:56:02.3070 [ ] dvb/dvb.cpp:2147 getNextSourceSpan seeking relative to end. len=16785000, seek = -1000 ), and the pcr pid is 17de, and the text pid is 17f2 13:56:02.3071 [ ] dvb/tstools.cpp:651 takeSamples samples step 41942988, pts begin 5456474852, pts end 5473259852, offs begin 12056, offs end 360939908: 13:56:02.3073 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=12056 pts=5456474852 13:56:02.3076 [ ] dvb/tstools.cpp:694 takeSample adding sample 12056: pts 0 -> pos 12056 (diff 0 bytes) 13:56:02.3132 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:56:02.3139 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:56:02.3140 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:56:02.3141 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0x17e8) - audio - ok 13:56:02.3142 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 13:56:02.3144 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 13:56:02.3145 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:56:02.3146 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 13:56:02.3206 [ ] dvb/decoder.cpp:267 eDVBVideo Video Device: /dev/dvb/adapter0/video0 13:56:02.3207 [ ] dvb/decoder.cpp:274 eDVBVideo demux device: /dev/dvb/adapter0/demux7 13:56:02.3208 < > dvb/decoder.cpp:354 startPid VIDEO_SET_STREAMTYPE 1 - ok 13:56:02.3209 < > dvb/decoder.cpp:389 startPid DMX_SET_PES_FILTER(0x17de) - video - ok 13:56:02.3209 < > dvb/decoder.cpp:397 startPid DEMUX_START - video - ok 13:56:02.3211 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 13:56:02.3212 < > dvb/decoder.cpp:412 startPid VIDEO_PLAY - ok 13:56:02.3266 < > dvb/decoder.cpp:779 startPid DMX_SET_PES_FILTER(0x17f2) - ttx - ok 13:56:02.3267 < > dvb/decoder.cpp:787 startPid DEMUX_START - ttx - ok 13:56:02.3312 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:02.3314 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:56:02.3315 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:56:02.3316 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:56:02.3375 [ ] dvb/teletext.cpp:640 setPageAndMagazine disable teletext subtitles page ffffffffffffffff (und) 13:56:02.3492 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 13:56:02.3496 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 18 13:56:02.3499 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 13:56:02.3516 [ ] service/servicedvb.cpp:2817 switchToTimeshift eDVBServicePlay::switchToTimeshift, in pause mode now. 13:56:02.3517 [ ] service/servicedvb.cpp:1569 pause eDVBServicePlay::pause 13:56:02.3517 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=6110, apid=6120 13:56:02.3518 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:02.3519 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:56:02.3520 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 13:56:02.3539 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:56:02.3581 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 1 audio stream(s) (17e8), and the pcr pid is 17de, and the text pid is 17f2 13:56:02.3587 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:56:02.3592 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:56:02.3593 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=6110, apid=6120 13:56:02.3620 [ ] service/servicedvb.cpp:1569 pause eDVBServicePlay::pause 13:56:02.3662 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=41880784 pts=5458436852 13:56:02.3665 [ ] dvb/tstools.cpp:694 takeSample adding sample 41955044: pts 1962000 -> pos 41880784 (diff -74260 bytes) 13:56:02.3667 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=79219276 pts=5460168452 13:56:02.3668 [ ] dvb/tstools.cpp:694 takeSample adding sample 83898032: pts 3693600 -> pos 79219276 (diff -4678756 bytes) 13:56:02.3671 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=125830492 pts=5462326652 13:56:02.3673 [ ] dvb/tstools.cpp:694 takeSample adding sample 125841020: pts 5851800 -> pos 125830492 (diff -10528 bytes) 13:56:02.3675 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=167767088 pts=5464279652 13:56:02.3676 [ ] dvb/tstools.cpp:694 takeSample adding sample 167784008: pts 7804800 -> pos 167767088 (diff -16920 bytes) 13:56:02.3781 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=209711580 pts=5466232652 13:56:02.3783 [ ] dvb/tstools.cpp:694 takeSample adding sample 209726996: pts 9757800 -> pos 209711580 (diff -15416 bytes) 13:56:02.3784 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=251660396 pts=5468174852 13:56:02.3786 [ ] dvb/tstools.cpp:694 takeSample adding sample 251669984: pts 11700000 -> pos 251660396 (diff -9588 bytes) 13:56:02.3788 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=293608648 pts=5470120652 13:56:02.3789 [ ] dvb/tstools.cpp:694 takeSample adding sample 293612972: pts 13645800 -> pos 293608648 (diff -4324 bytes) 13:56:02.3790 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=335538664 pts=5472075452 13:56:02.3790 [ ] dvb/tstools.cpp:694 takeSample adding sample 335555960: pts 15600600 -> pos 335538664 (diff -17296 bytes) 13:56:02.3791 [ ] dvb/tstools.cpp:411 getOffset using: 15600600:16785000 -> 335538664:360939908 13:56:02.3792 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=356888132 pts=5473061852 13:56:02.3793 [ ] dvb/tstools.cpp:694 takeSample adding sample 360918452: pts 16587000 -> pos 356888132 (diff -4030320 bytes) 13:56:02.3794 [ ] dvb/tstools.cpp:444 getOffset calculated diff -2188 ms 13:56:02.3795 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:56:02.3795 [ ] dvb/tstools.cpp:411 getOffset using: 16587000:16785000 -> 356888132:360939908 13:56:02.3796 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=356888132 pts=5473061852 13:56:02.3797 [ ] dvb/tstools.cpp:694 takeSample adding sample 360919392: pts 16587000 -> pos 356888132 (diff -4031260 bytes) 13:56:02.3797 [ ] dvb/tstools.cpp:444 getOffset calculated diff -2188 ms 13:56:02.3798 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:56:02.3798 [ ] dvb/tstools.cpp:411 getOffset using: 16587000:16785000 -> 356888132:360939908 13:56:02.3799 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=356888132 pts=5473061852 13:56:02.3800 [ ] dvb/tstools.cpp:694 takeSample adding sample 360919392: pts 16587000 -> pos 356888132 (diff -4031260 bytes) 13:56:02.3800 [ ] dvb/tstools.cpp:444 getOffset calculated diff -2188 ms 13:56:02.3801 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:56:02.3801 [ ] dvb/tstools.cpp:411 getOffset using: 16587000:16785000 -> 356888132:360939908 13:56:02.3802 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=356888132 pts=5473061852 13:56:02.3803 [ ] dvb/tstools.cpp:694 takeSample adding sample 360919392: pts 16587000 -> pos 356888132 (diff -4031260 bytes) 13:56:02.3804 [ ] dvb/tstools.cpp:444 getOffset calculated diff -2188 ms 13:56:02.3804 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:56:02.3805 [ ] dvb/tstools.cpp:411 getOffset using: 16587000:16785000 -> 356888132:360939908 13:56:02.3805 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=356888132 pts=5473061852 13:56:02.3806 [ ] dvb/tstools.cpp:694 takeSample adding sample 360919392: pts 16587000 -> pos 356888132 (diff -4031260 bytes) 13:56:02.3807 [ ] dvb/tstools.cpp:444 getOffset calculated diff -2188 ms 13:56:02.3807 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 13:56:02.3808 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 360919392 as offset for 16587000 13:56:02.3808 [ ] dvb/dvb.cpp:2193 getNextSourceSpan ok, resolved skip (rel: 0, diff 16587000), now at 15833160 13:56:02.4039 [ ] service/servicedvb.cpp:1595 seekTo eDVBServicePlay::seekTo: jump -90000 13:56:02.4041 [ ] dvb/dvb.cpp:1942 cueSheetEvent seek. 13:56:02.4041 [ ] base/filepush.cpp:306 pause FILEPUSH waiting for pause 13:56:02.4042 [ ] base/filepush.cpp:177 thread wait for driver eof aborted by signal 13:56:02.4043 [ ] base/filepush.cpp:259 thread FILEPUSH THREAD PAUSED 13:56:02.4044 < > dvb/decoder.cpp:179 flush AUDIO_CLEAR_BUFFER - ok 13:56:02.4371 < > dvb/decoder.cpp:446 flush VIDEO_CLEAR_BUFFER - ok 13:56:02.4449 [ ] dvb/dvb.cpp:2147 getNextSourceSpan seeking relative to end. len=16788600, seek = -90000 13:56:02.4450 [ ] dvb/tstools.cpp:411 getOffset using: 16587000:16785000 -> 356888132:360939908 13:56:02.4451 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=359152780 pts=5473171652 13:56:02.4452 [ ] dvb/tstools.cpp:694 takeSample adding sample 359171744: pts 16696800 -> pos 359152780 (diff -18964 bytes) 13:56:02.4453 [ ] dvb/tstools.cpp:444 getOffset calculated diff -20 ms 13:56:02.4455 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 359171744 as offset for 16696800 13:56:02.4455 [ ] dvb/dvb.cpp:2193 getNextSourceSpan ok, resolved skip (rel: 0, diff 16696800), now at 156886a0 13:56:02.4465 [ ] dvb/dvb.cpp:1928 pvrEvent eDVBChannel: pvrEvent evtStopped 13:56:02.4471 [ ] dvb/pmt.cpp:202 PATready PATready 13:56:02.4472 [ ] dvb/pmt.cpp:213 PATready PAT TSID: 0x03f3 (1011) 13:56:02.4473 [ ] dvb/pmt.cpp:242 PATready use pmtpid 17d4 for service_id 2b66 13:56:02.4591 [ ] service/servicedvb.cpp:1235 serviceEventTimeshift eventNewProgramInfo TS 13:56:02.4630 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 4 audio stream(s) (17e8, 17e9, 17ea, 17eb), and the pcr pid is 17de, and the text pid is 17f2 13:56:02.4639 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:56:02.4646 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:56:02.4647 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=6110, apid=6120 13:56:02.4830 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 13:56:02.4837 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 18 13:56:02.4841 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 13:56:02.4863 [ ] driver/rcinput.cpp:38 handleCode 0 a8 1 13:56:02.5312 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:56:02.5329 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:56:02.5431 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:56:02.6005 { } Components/AVSwitch.py:322 setWss [VideoMode] setting wss: auto 13:56:02.6017 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 13:56:02.6036 { } Components/AVSwitch.py:345 setPolicy169 [VideoMode] setting policy2: scale 13:56:02.7804 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 13:56:02.9458 [ ] service/servicedvb.cpp:1476 setFastForward eDVBServicePlay::setFastForward(-2) 13:56:02.9459 [ ] service/servicedvb.cpp:1506 setFastForward_internal setting cue skipmode to -2 13:56:02.9460 [ ] dvb/dvb.cpp:1957 cueSheetEvent skipmode ratio is -180000:90000, bitrate is 15481560 bit/s 13:56:02.9460 [ ] dvb/dvb.cpp:1967 cueSheetEvent resolved to: -996788 524288 13:56:02.9461 [ ] dvb/dvb.cpp:1985 cueSheetEvent flush pvr 13:56:02.9462 [ ] base/filepush.cpp:306 pause FILEPUSH waiting for pause 13:56:02.9462 [ ] base/filepush.cpp:177 thread wait for driver eof aborted by signal 13:56:02.9463 [ ] base/filepush.cpp:259 thread FILEPUSH THREAD PAUSED 13:56:02.9464 < > dvb/decoder.cpp:179 flush AUDIO_CLEAR_BUFFER - ok 13:56:02.9829 < > dvb/decoder.cpp:446 flush VIDEO_CLEAR_BUFFER - ok 13:56:02.9913 [ ] dvb/dvb.cpp:1987 cueSheetEvent done 13:56:02.9914 [ ] dvb/decoder.cpp:829 setState decoder state: trickmode, vpid=6110, apid=6120 13:56:02.9915 < > dvb/decoder.cpp:159 stop AUDIO_STOP - 13:56:02.9916 [ ] dvb/tstools.cpp:411 getOffset using: 16696800:16785000 -> 359152780:360939908 13:56:02.9917 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=359187944 pts=5473178852 13:56:02.9918 [ ] dvb/tstools.cpp:694 takeSample adding sample 359335116: pts 16704000 -> pos 359187944 (diff -147172 bytes) 13:56:02.9919 [ ] dvb/tstools.cpp:444 getOffset calculated diff -20 ms 13:56:02.9919 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 359335116 as offset for 16704000 13:56:02.9920 [ ] dvb/dvb.cpp:2193 getNextSourceSpan ok, resolved skip (rel: 1, diff 16704000), now at 156b04cc ok 13:56:03.0239 < > dvb/decoder.cpp:167 stop DEMUX_STOP - audio - ok 13:56:03.0241 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:56:03.0245 < > dvb/decoder.cpp:800 stop DEMUX_STOP - ttx - ok 13:56:03.0255 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:03.0259 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(1) - ok 13:56:03.0261 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:56:03.0604 [ ] dvb/dvb.cpp:1928 pvrEvent eDVBChannel: pvrEvent evtStopped 13:56:03.7192 [ ] driver/rcinput.cpp:38 handleCode 1 cf 1 13:56:03.7209 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS playpauseService 13:56:03.7221 [ ] service/servicedvb.cpp:1582 unpause eDVBServicePlay::unpause 13:56:03.7222 [ ] service/servicedvb.cpp:1506 setFastForward_internal setting cue skipmode to 0 13:56:03.7223 [ ] dvb/dvb.cpp:1976 cueSheetEvent skipmode ratio is 0, normal play 13:56:03.7228 [ ] dvb/tstools.cpp:411 getOffset using: 15600600:16587000 -> 335538664:356888132 13:56:03.7230 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=350566256 pts=5472779252 13:56:03.7230 [ ] dvb/tstools.cpp:694 takeSample adding sample 350615676: pts 16304400 -> pos 350566256 (diff -49420 bytes) 13:56:03.7232 [ ] dvb/tstools.cpp:444 getOffset calculated diff 80 ms 13:56:03.7232 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 350615676 as offset for 16304400 13:56:03.7233 [ ] dvb/dvb.cpp:2193 getNextSourceSpan ok, resolved skip (rel: 1, diff 16304400), now at 14e5f87c 13:56:03.7234 [ ] dvb/dvb.cpp:1985 cueSheetEvent flush pvr 13:56:03.7235 [ ] base/filepush.cpp:306 pause FILEPUSH waiting for pause 13:56:03.7237 [ ] base/filepush.cpp:259 thread FILEPUSH THREAD PAUSED 13:56:03.7238 < > dvb/decoder.cpp:446 flush VIDEO_CLEAR_BUFFER - ok 13:56:03.7316 [ ] dvb/dvb.cpp:1987 cueSheetEvent done 13:56:03.7317 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:56:03.7322 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0x17e8) - audio - ok 13:56:03.7323 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 13:56:03.7331 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 13:56:03.7333 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:56:03.7334 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 13:56:03.7431 < > dvb/decoder.cpp:779 startPid DMX_SET_PES_FILTER(0x17f2) - ttx - ok 13:56:03.7433 < > dvb/decoder.cpp:787 startPid DEMUX_START - ttx - ok 13:56:03.7442 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:03.7446 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:56:03.7450 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:56:03.7623 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:56:03.7774 [ ] dvb/dvb.cpp:1928 pvrEvent eDVBChannel: pvrEvent evtStopped 13:56:03.9268 [ ] driver/rcinput.cpp:38 handleCode 0 cf 1 13:56:03.9655 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:56:06.8799 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:56:06.8812 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS pauseService 13:56:06.8820 [ ] service/servicedvb.cpp:1569 pause eDVBServicePlay::pause 13:56:06.8822 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=6110, apid=6120 13:56:06.8822 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:06.8823 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:56:06.8824 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 13:56:06.8826 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:56:07.0845 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:56:07.1117 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:56:08.8330 [ ] driver/rcinput.cpp:38 handleCode 1 cf 1 13:56:08.8345 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS playpauseService 13:56:08.8357 [ ] service/servicedvb.cpp:1582 unpause eDVBServicePlay::unpause 13:56:08.8358 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:56:08.8359 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:08.8360 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:56:08.8361 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:56:08.8362 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:56:09.0388 [ ] driver/rcinput.cpp:38 handleCode 0 cf 1 13:56:09.0677 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:56:10.4342 [ ] driver/rcinput.cpp:38 handleCode 1 d0 1 13:56:10.6395 [ ] driver/rcinput.cpp:38 handleCode 0 d0 1 13:56:10.6709 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS seekFwd 13:56:10.6722 [ ] service/servicedvb.cpp:1476 setFastForward eDVBServicePlay::setFastForward(2) 13:56:10.6723 [ ] dvb/decoder.cpp:829 setState decoder state: decoderfastforward, vpid=6110, apid=6120 13:56:10.6724 < > dvb/decoder.cpp:159 stop AUDIO_STOP - ok 13:56:10.7018 < > dvb/decoder.cpp:167 stop DEMUX_STOP - audio - ok 13:56:10.7019 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:56:10.7020 < > dvb/decoder.cpp:800 stop DEMUX_STOP - ttx - ok 13:56:10.7025 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:10.7027 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(2) - ok 13:56:10.7028 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:56:11.0676 [ ] driver/rcinput.cpp:38 handleCode 1 d0 1 13:56:11.2684 [ ] driver/rcinput.cpp:38 handleCode 0 d0 1 13:56:11.3019 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS seekFwd 13:56:11.3046 [ ] service/servicedvb.cpp:1476 setFastForward eDVBServicePlay::setFastForward(4) 13:56:11.3047 [ ] dvb/decoder.cpp:829 setState decoder state: decoderfastforward, vpid=6110, apid=6120 13:56:11.3047 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:11.3050 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(4) - ok 13:56:11.3050 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:56:12.3754 [ ] driver/rcinput.cpp:38 handleCode 1 d0 1 13:56:12.5805 [ ] driver/rcinput.cpp:38 handleCode 0 d0 1 13:56:12.6158 { } Components/ActionMap.py:46 action action -> InfobarSeekActionsPTS seekFwd 13:56:12.6184 [ ] service/servicedvb.cpp:1476 setFastForward eDVBServicePlay::setFastForward(8) 13:56:12.6186 [ ] dvb/decoder.cpp:829 setState decoder state: decoderfastforward, vpid=6110, apid=6120 13:56:12.6186 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:12.6187 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(8) - ok 13:56:12.6189 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:56:13.3323 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 13:56:13.5850 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 13:56:13.8140 [ ] driver/rcinput.cpp:38 handleCode 1 d0 1 13:56:13.8382 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 13:56:13.8864 [ ] base/filepush.cpp:174 thread wait for driver eof ok 13:56:13.8866 [ ] base/filepush.cpp:202 thread reached EOF, but the file may grow. delaying 1 second. 13:56:13.8873 [ ] dvb/dvb.cpp:1920 pvrEvent eDVBChannel: End of file! 13:56:13.8875 [ ] service/servicedvb.cpp:1295 serviceEventTimeshift timeshift EOF, so let's go live 13:56:13.8875 [ ] service/servicedvb.cpp:2742 switchToLive SwitchToLive 13:56:13.8879 [ ] base/filepush.cpp:286 stop eFilePushThread stopping thread 13:56:13.8881 [ ] base/filepush.cpp:267 thread FILEPUSH THREAD STOP 13:56:14.0152 [ ] dvb/decoder.cpp:829 setState decoder state: decoderfastforward, vpid=-1, apid=-1 13:56:14.0153 < > dvb/decoder.cpp:425 stop DEMUX_STOP - video - ok 13:56:14.0155 < > dvb/decoder.cpp:434 stop VIDEO_STOP - ok 13:56:14.0265 < > service/servicedvb.cpp:2834 updateDecoder have 1 video stream(s) (17de), and 4 audio stream(s) (17e8, 17e9, 17ea, 17eb), and the pcr pid is 17de, and the text pid is 17f2 13:56:14.0327 [ ] service/servicedvb.cpp:3518 setAC3Delay Setting audio delay: setAC3Delay, 0 + 0 13:56:14.0331 [ ] service/servicedvb.cpp:3529 setPCMDelay Setting audio delay: setPCMDelay, 0 + 0 13:56:14.0332 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=6110, apid=6120 13:56:14.0333 < > dvb/decoder.cpp:702 startPid DMX_SET_PES_FILTER(0x17de) - pcr - ok 13:56:14.0334 < > dvb/decoder.cpp:710 startPid DEMUX_START - pcr - ok 13:56:14.0336 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0x17e8) - audio - ok 13:56:14.0338 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 13:56:14.0340 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 13:56:14.0341 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 13:56:14.0341 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 13:56:14.0401 [ ] dvb/decoder.cpp:267 eDVBVideo Video Device: /dev/dvb/adapter0/video0 13:56:14.0402 [ ] dvb/decoder.cpp:274 eDVBVideo demux device: /dev/dvb/adapter0/demux0 13:56:14.0403 < > dvb/decoder.cpp:354 startPid VIDEO_SET_STREAMTYPE 1 - ok 13:56:14.0405 < > dvb/decoder.cpp:389 startPid DMX_SET_PES_FILTER(0x17de) - video - ok 13:56:14.0405 < > dvb/decoder.cpp:397 startPid DEMUX_START - video - ok 13:56:14.0407 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 13:56:14.0408 < > dvb/decoder.cpp:412 startPid VIDEO_PLAY - ok 13:56:14.0462 < > dvb/decoder.cpp:779 startPid DMX_SET_PES_FILTER(0x17f2) - ttx - ok 13:56:14.0464 < > dvb/decoder.cpp:787 startPid DEMUX_START - ttx - ok 13:56:14.0509 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 13:56:14.0511 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 13:56:14.0512 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 13:56:14.0513 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 13:56:14.0515 [ ] dvb/teletext.cpp:640 setPageAndMagazine disable teletext subtitles page ffffffffffffffff (und) 13:56:14.0537 [ ] service/servicedvb.cpp:1582 unpause eDVBServicePlay::unpause 13:56:14.0625 [ ] driver/rcinput.cpp:38 handleCode 0 d0 1 13:56:14.3943 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:56:14.3954 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:56:14.4029 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 13:56:14.4573 { } Components/AVSwitch.py:322 setWss [VideoMode] setting wss: auto 13:56:14.4582 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 13:56:14.4596 { } Components/AVSwitch.py:345 setPolicy169 [VideoMode] setting policy2: scale 13:56:14.6175 [ ] dvb/pmt.cpp:288 AITready AITready 13:56:14.6177 [ ] dvb/pmt.cpp:307 AITready Section Length : 432, Total Section Length : 435 13:56:14.6177 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 1 13:56:14.6179 [ ] dvb/pmt.cpp:282 saveData Save Data Len : [435] 13:56:14.6180 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 6 13:56:14.6181 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 2 13:56:14.6182 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 7 13:56:14.6182 [ ] dvb/pmt.cpp:318 AITready found applicaions ids >> pid : 68742074, orgid : 17, appid : 8 13:56:14.6183 [ ] dvb/pmt.cpp:452 AITready Found : control[1], name[ZDF start], url[http://hbbtv.zdf.de/zdfstart/index.php] 13:56:14.6185 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF programm], url[http://hbbtv.zdf.de/zdfepg/index.php] 13:56:14.6186 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF mediathek], url[http://hbbtv.zdf.de/zdfm3/index.php] 13:56:14.6186 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDF nachrichten], url[http://hbbtv.zdf.de/zdfnews/index.php] 13:56:14.6187 [ ] dvb/pmt.cpp:452 AITready Found : control[2], name[ZDFtivi], url[http://hbbtv.zdf.de/zdfm3/index.php?tivi=1] 13:56:15.5583 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF start 13:56:15.5587 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfstart/index.php 13:56:15.5591 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:56:15.5595 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 1 13:56:15.5598 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 1 13:56:15.5601 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:56:15.5603 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:56:15.5608 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF programm 13:56:15.5611 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfepg/index.php 13:56:15.5615 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:56:15.5618 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 6 13:56:15.5621 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:56:15.5624 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:56:15.5627 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:56:15.5630 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF mediathek 13:56:15.5633 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfm3/index.php 13:56:15.5637 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:56:15.5640 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 2 13:56:15.5643 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:56:15.5648 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:56:15.5651 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:56:15.5655 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDF nachrichten 13:56:15.5658 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfnews/index.php 13:56:15.5661 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:56:15.5664 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 7 13:56:15.5668 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:56:15.5671 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:56:15.5674 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:56:15.5677 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : ZDFtivi 13:56:15.5681 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://hbbtv.zdf.de/zdfm3/index.php?tivi=1 13:56:15.5685 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 17 13:56:15.5688 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 8 13:56:15.5691 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 2 13:56:15.5695 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 13:56:15.5697 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 13:56:18.0772 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:56:18.0792 { } Components/ActionMap.py:46 action action -> InfobarTimeshiftSeekPointerActions SeekPointerPlay 13:56:18.0805 { W } Components/ActionMap.py:53 action unknown action InfobarTimeshiftSeekPointerActions/SeekPointerPlay! typo in keymap? 13:56:18.2764 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:56:18.3085 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:56:18.9107 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:56:18.9121 { } Components/ActionMap.py:46 action action -> InfobarTimeshiftSeekPointerActions SeekPointerPlay 13:56:18.9125 { W } Components/ActionMap.py:53 action unknown action InfobarTimeshiftSeekPointerActions/SeekPointerPlay! typo in keymap? 13:56:19.1165 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:56:19.1478 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:56:19.3602 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:56:19,359 - DEBUG - IBTS hide 13:56:19.3723 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:56:19,371 - DEBUG - IBTS timerHide 13:56:19.3746 { } /usr/lib/python2.7/logging/__init__.py:882 emit 2017-11-05 13:56:19,373 - DEBUG - IBTS tunerHide 13:56:19.4064 [ ] driver/rcinput.cpp:38 handleCode 1 77 1 13:56:19.6124 [ ] driver/rcinput.cpp:38 handleCode 0 77 1 13:56:19.6454 { } Components/Timeshift.py:397 startTimeshift [TIMESHIFT] - hu, timeshift already enabled? 13:57:36.4409 [ ] components/file_eraser.cpp:88 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift/pts_livebuffer_2.sc.del'