[AdvancedMovieSelection] load skin: /usr/lib/enigma2/python/Plugins/Extensions/AdvancedMovieSelection/skin/1280.xml [AdvancedMovieSelection] Set changes locale to de [AdvancedMovieSelection] Set tmdb locale to de [AdvancedMovieSelection] Set tvdb locale to de [AdvancedMovieSelection] Set tmdb poster size to w185 [AdvancedMovieSelection] Set tmdb backdrop size to w1280 Preferred tag editor changed to It's now Thu Jan 1 01:01:09 1970 [timer.py] next activation: 169 (in 99139 ms) [WebInterface] fallback to default translation for Webinterface [TeleText] set language to [de] [TeleText] set language to [de] [TeleText] fallback to default translation for bilinear [TeleText] plugins Flashexpander set language to de [AudioSync] set language to de [AudioSync] set language to de [ResourceManager] adding: software_manager [ResourceManager] adding: software_manager_upgrade getModeList for port DVI-PC getModeList for port YPbPr getModeList for port Scart getModeList for port DVI-PC getModeList for port DVI hotplug on dvi -> setting aspect, policy, polic[ 73.516000] set current avs auto aspect to 16:9 y2, wss 16:9 pan[ 73.521000] disable wss on display 0 scan letterbox a[ 73.526000] set wss 7 on display 1 uto [ 73.532000] set sb to 3(1) [ 73.537000] set current avs auto aspect to 16:9 [ 73.542000] disable wss on display 0 [ 73.546000] set wss 7 on display 1 [ 73.549000] set sb to 3(1) [ 73.554000] disable wss on display 0 [ 73.557000] set wss 7 on display 1 [ 73.563000] set current avs auto aspect to 16:9 [ 73.567000] disable wss on display 0 [ 73.571000] set wss 7 on display 1 [ 73.575000] set sb to 3(1) -> setting aspect, policy, polic[ 73.582000] set current avs auto aspect to 16:9 y2, wss 16:9 pan[ 73.587000] disable wss on display 0 scan letterbox a[ 73.592000] set wss 7 on display 1 uto [ 73.597000] set sb to 3(1) [ 73.603000] set current avs auto aspect to 16:9 [ 73.607000] disable wss on display 0 [ 73.611000] set wss 7 on display 1 [ 73.615000] set sb to 3(1) [ 73.619000] disable wss on display 0 [ 73.623000] set wss 7 on display 1 [ 73.628000] set current avs auto aspect to 16:9 [ 73.633000] disable wss on display 0 [ 73.637000] set wss 7 on display 1 [ 73.640000] set sb to 3(1) -> setting aspect, policy, polic[ 73.650000] set current avs auto aspect to 16:9 y2, wss 16:9 pan[ 73.656000] disable wss on display 0 scan letterbox a[ 73.660000] set wss 7 on display 1 uto [ 73.665000] set sb to 3(1) [ 73.670000] set current avs auto aspect to 16:9 [ 73.674000] disable wss on display 0 [ 73.678000] set wss 7 on display 1 [ 73.681000] set sb to 3(1) [ 73.685000] disable wss on display 0 [ 73.689000] set wss 7 on display 1 [ 73.695000] set current avs auto aspect to 16:9 [ 73.700000] disable wss on display 0 [ 73.703000] set wss 7 on display 1 [ 73.707000] set sb to 3(1) -> setting aspect, policy, polic[ 73.715000] set current avs auto aspect to 16:9 y2, wss 16:9 pan[ 73.721000] disable wss on display 0 scan letterbox a[ 73.726000] set wss 7 on display 1 uto [ 73.730000] set sb to 3(1) [ 73.735000] set current avs auto aspect to 16:9 [ 73.740000] disable wss on display 0 [ 73.744000] set wss 7 on display 1 [ 73.748000] set sb to 3(1) [ 73.754000] disable wss on display 0 [ 73.758000] set wss 7 on display 1 [ 73.764000] set current avs auto aspect to 16:9 [ 73.769000] disable wss on display 0 [ 73.772000] set wss 7 on display 1 [ 73.776000] set sb to 3(1) setMode - port: DVI mode: 1080i rate: 60Hz [ 73.791000] using 60.00Hz as MonitorRefreshRate [ 73.931000] SET FORMAT: display[0], format=21 [ 73.936000] HDMI mode [ 73.938000] use EDID to detect colorspace [ 73.942000] using colorspace: 1 [ 74.006000] bcm_video_analog_port_setup (fmt=0) [ 74.121000] SET FORMAT: display[1], format=0 [ 74.218000] set current avs auto aspect to 16:9 [ 74.223000] disable wss on display 0 [ 74.227000] set wss 7 on display 1 [ 74.231000] set sb to 3(1) [ 74.235000] set current avs auto aspect to 16:9 [ 74.241000] disable wss on display 0 [ 74.245000] set wss 7 on display 1 [ 74.248000] set sb to 3(1) libpagecache exists... drop caches now! -> setting aspect, policy, polic[ 74.260000] set current avs auto aspect to 16:9 y2, wss 16:9 pan[ 74.266000] disable wss on display 0 scan letterbox a[ 74.270000] set wss 7 on display 1 uto [ 74.277000] set sb to 3(1) [ 74.282000] set current avs auto aspect to 16:9 [ 74.287000] disable wss on display 0 [ 74.291000] set wss 7 on display 1 [ 74.295000] set sb to 3(1) [ 74.300000] disable wss on display 0 [ 74.305000] set wss 7 on display 1 [ 74.311000] set current avs auto aspect to 16:9 [ 74.316000] disable wss on display 0 [ 74.320000] set wss 7 on display 1 [ 74.325000] set sb to 3(1) sh: fpupgrade: not found /usr/share/enigma2/ [PluginSort] Fixing weight for InfoBarTunerState (was 0, now -1) [PluginSort] Fixing weight for IMDb Details (was 0, now 7) [PluginSort] Fixing weight for OFDb Details (was 0, now 7) [PluginSort] Splitting Gemini3 Wizard up in individual entries ([2, 0]) [PluginSort] Fixing weight for Dream-Explorer (was 0, now 16) [PluginSort] Fixing weight for MediaScanner (was 0, now 14) [PluginSort] Fixing weight for GP3 Sicherungsmanager (was 0, now 0) [PluginSort] Fixing weight for Terminal (was 0, now 0) [PluginSort] Fixing weight for Samba Einstell. (was 0, now 0) [PluginSort] Fixing weight for Show InfoBarTunerState (was 0, now 0) [PluginSort] Fixing weight for Downloads (was 0, now 0) [PluginSort] Fixing weight for Cutlist Editor (was 0, now 0) [PluginSort] Fixing weight for IMDb Details (was 0, now 0) [PluginSort] Fixing weight for OFDb Details (was 0, now 0) [PluginSort] Splitting Plugin up in individual entries ([7, 5]) ------------------------------------------------------------ [OpenPanel] generating /etc/plugin.xml ------------------------------------------------------------ [PluginSort] Splitting EPGRefresh up in individual entries ([5, 7]) [PluginSort] Splitting Plugin up in individual entries ([7, 5]) [TeleText] autostart(0, {}) [PluginSort] Splitting Plugin up in individual entries ([7, 5]) [CrashlogAutoSubmit] - autostart [CI_Assignment] activating ci configs: [CI_Activate] FOUND 4 CI Slots [CI_Activate_Config_CI0] no config file found [CI_Activate_Config_CI0] error parsing xml... [CI_Activate_Config_CI1] no config file found [CI_Activate_Config_CI1] error parsing xml... [CI_Activate_Config_CI2] no config file found [CI_Activate_Config_CI2] error parsing xml... [CI_Activate_Config_CI3] no config file found [CI_Activate_Config_CI3] error parsing xml... starting hotplug handler add device sde to bdpoll current state: medium removed add device sdf to bdpoll current state: medium inserted add device sdd to bdpoll current state: medium removed add device sdb to bdpoll current state: medium inserted add device sdc to bdpoll current state: medium inserted [PluginSort] Splitting Plugin up in individual entries ([7, 5]) [PluginSort] Splitting Plugin up in individual entries ([7, 5]) [AspectRatioSwitch Ver. 1.0] Initiation disabled [AspectRatioSwitch Ver. 1.0] Aspect modes in cycle: [3, 6] [AspectRatioSwitch Ver. 1.0] Aspect modes in cycle: [3, 6] couldn't open /proc/stb/misc/12V_output geminimain set language to de [WebInterface] set language to de [WebInterface] set language to de [WebInterface] set language to de [AdvancedMovieSelection] Set changes locale to de [AdvancedMovieSelection] Set tmdb locale to de [AdvancedMovieSelection] Set tvdb locale to de [TeleText] set language to [de] [TeleText] set langu[ 75.670000] 21492 ms: 66 age to [de] Flashexpander set language to de [AudioSync] set language to de [AudioSync] set language to de [ 75.688000] 15 ms: 95 [ 75.696000] tsmux set ciclk reg fe to val 00 [ 75.706000] tsmux set ciclk reg fe to val 00 [ 75.716000] tsmux set ciclk reg fe to val 00 [ 75.725000] tsmux set ciclk reg fe to val 00 [ 75.732000] 41 ms: 95 It's now Thu Jan 1 01:01:15 1970 [timer.py] next activation: 175 (in 99259 ms) [TIMER] record time changed, start prepare is now: Wed Feb 12 02:06:40 2014 sanitycheck check checkTimerlist recording service: 1:0:19:23F4:5F:1:FFFF0192:0:0:0: record: 0 [Timer] Record ProcessRepeated It's now Thu Jan 1 01:01:15 1970 next real activation is Wed Feb 12 02:06:40 2014 [timer.py] next activation: 175 (in 99217 ms) [TIMER] record time changed, start prepare is now: Wed Feb 12 23:31:40 2014 sanitycheck check checkTimerlist recording service: 1:0:19:23F4:5F:1:FFFF0192[CrashlogAutoSubmit] - not starting CrashlogAutoSubmit [AspectRatioSwitch Ver. 1.0] Initiation disabled [Toplevel.importExternalModules] Imported external module: Example [Toplevel.importExternalModules] Imported external module: EPGRefresh [HTTPRootResource}.__init__ [Webinterface] started on 0.0.0.0:80 auth=False ssl=False [WebInterface.registerBonjourService] No module named Bonjour.Bonjour [HTTPRootResource}.__init__ [Webinterface] started on 0.0.0.0:443 auth=True [ 78.720000] 2986 ms: 95 ssl=True [WebInterface.registerBonjourService] No module named Bonjour.Bonjour [EPGC] set outdated epg timespan to 72 hours... [EPGC] set cache timespan to 28 days! [EPGC] setCacheFile read/write epg data from/to '/media/cf/epg.dat' not showing fine-tuning wizard, config variable doesn't exist showtestcard is false Looking for embedded skin [ 78.793000] 70 ms: 99 setValue 75 [ 78.855000] set mute 0 (000) 1 cannot open /proc/stb/audio/j1_m[ 78.861000] 66 ms: 99 ute(No such file or directory) Setvolume: 100 100 (raw) Setvolume: 0 0 (-1db) Setvolume: 75 75 (raw) Setvolume: 16 16 (-1db) [ 78.885000] 21 ms: 99 [update hutplug] ['/dev/sdc1 on /media/cf type ext3 (rw,relatime,errors=continue,barrier=1,data=writeback)', '/dev/sdb1 on /media/sd type ext3 (rw,relatime,errors=continue,barrier=1,data=writeback)', '/dev/sdf1 on /media/intusb type ext3 (rw,relatime,errors=continue,barrier=1,data=writeback)', '/dev/sda5 on /media/PR type ext3 (rw,relatime,errors=continue,barrier=1,data=writeback)', '/dev/sda6 on /media/hdd type ext3 (rw,relatime,errors=continue,barrier=1,data=writeback)'] Unable to determine structure of /dev fallback to udev new Harddisk sdc -> /dev/sdc -> /dev/sdc [ePopen] command: ('sdparm', 'sdparm', '--set=SCT=0', '/dev/sdc') Unable to determine structure of /dev fallback to udev new Harddisk sdb -> /dev/sdb -> /dev/sdb [ePopen] command: ('sdparm', 'sdparm', '--set=SCT=0', '/dev/sdb') Unable to determine structure of /dev fallback to udev new Harddisk sdf -> /dev/sdf -> /dev/sdf [ePopen] command: ('sdparm', 'sdparm', '--set=SCT=0', '/dev/sdf') Unable to determine structure of /dev fallback to udev new Harddisk sda -> /dev/sda -> /dev/sda [ePopen] command: ('hdparm', 'hdparm', '-S0', '/dev/sda') checking directories [] [] checkAllAvailable in 0.147207 sec allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! RemovePopup, id = ZapError [HbbTV] connecting callbacks allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! main thread is non-idle! display spinner! allocating new converter! reused converter! reused converter! allocating new converter! allocating new converter! allocating new converter! CISlot 0 what 2 CISlot 1 what 2 CISlot 2 what 2 CISlot 3 what 2 child has terminated pipes closed child has terminated pipes closed child has terminated pipes closed [ePopen] command: route -n | grep eth0 poll: unhandled POLLERR/HUP/NVAL for fd 42(16) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 46(16) poll: unhandled POLLERR/HUP/NVAL for fd 49(16) child has terminated pipes closed child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 65(17) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 68(17) poll: unhandled POLLERR/HUP/NVAL for fd 71(17) poll: unhandled POLLERR/HUP/NVAL for fd 74(16) playing 1:0:19:235B:5B:1:FFFF016A:0:0:0: realpath /etc/enigma2/virtsubmapping.txt: No such file or directory can't open /etc/enigma2/virtsubmapping.txt: No such file or directory [TeleText] service started not pauseable. RemovePopup, id = ZapError allocate channel.. 005b:0001:ffff016a opening frontend 2 [ 81.874000] tda: init complete [eDVBCAService] new channel 0x25f7148! (2)tune tuning to 362000 khz, sr 6900000, fec 0, modulation 5, inversion 2 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:19:235B:5B:1:FFFF016A:0:0:0: recheckPMTHAndlers() recheck 0x25f4c8c 1:0:19:235B:5B:1:FFFF016A:0:0:0: check Slot 0 check Slot 1 check Slot 2 check Slot 3 allocate demux [HbbTV].aitInvalidated child has terminated pipes closed 0.0.0.0 169.254 192.168 nameservers: [[192, 168, 1, 1]] read configured interface: {'lo': {'dhcp': False}, 'eth0': {'dhcp': True}} self.ifaces after loading: {'eth0': {'preup': False, 'predown': False, 'ip': [192, 168, 1, 61], 'up': True, 'dns-nameservers': False, 'broadcast': False, 'mac': '00:09:34:1c:1f:84', 'dhcp': True, 'bcast': [192, 168, 1, 255], 'netmask': [255, 255, 255, 0], 'gateway': [192, 168, 1, 1]}} poll: unhandled POLLERR/HUP/NVAL for fd 42(16) [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (2)setting frontend (2)fe event: status 0, freq 362000000, inversion off, m_tuning 1 F [ 82.106000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 362000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0x25f7148 running [eDVBLocalTimeHandler] channel 0x25f7148 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0x25f7148 running stop release channel [ 82.139000] start pcr0 on dmx 3 pid:d0 index 5 timer no version filtering 001[ 82.146000] start audio on dmx 3 pid:db 2: 4e 23 5b 00 [ 82.151000] audio xpt start 00 00 mask: ff[ 82.156000] sync is off so we enable it ff ff 00 00 00[ 82.161000] demux-index 3 pcr0pid d0 mode: 00 00 00[ 82.166000] using 5 as pcr0 pidchannel 00 00 00 ok ..[ 82.171000] AC3 . now we start!![ 82.174000] audio: streamtype set 1 no version fil[ 82.179000] audio: streamtype_set: stop rave tering 0000: 0[ 82.185000] decode 0 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 eventNewProgramInfo 0 0 have 1 video stream(s) (00d0), and 1 audio stream[ 82.200000] audio: streamtype_set: restart rave (s) (00db), and [ 82.206000] set mute 0 (111) 1 the pcr pid is 0[ 82.211000] audio: decoder start 2 0d0, and the text pid is 00ee allocate demux disable teletext subtitles decoder state: play, vpid=208, apid=219 DMX_SET_PES_FILTER(0xd0) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FIL[ 82.232000] video delay 0 (0 ms) TER(0xdb) - audi[ 82.235000] total video delay: 0 (0 ms) o - ok DEMUX_START - audio - ok[ 82.242000] VIDEO0: set streamtype! AUDIO_SET_BYPA[ 82.247000] set H264 SS(0) - ok AUDI[ 82.251000] sw rave disabled! O_PAUSE - ok AU[ 82.255000] audio delay 18000 (200 ms) DIO_PLAY - ok V[ 82.260000] total video delay: 18000 (200 ms) ideo Device: /de[ 82.267000] start video0 on dmx 3 pid:d0 v/dvb/adapter0/v[ 82.272000] total video delay: 18000 (200 ms) ideo0 demux device: /dev/dvb/ad[ 82.279000] VIDEO0: start decode apter0/demux3 VIDEO_SET_STREAMTYPE 1 - ok DMX_SET_PES_FILTER(0xd0) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0xee) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok AUDIO_CHANNEL_SELECT(0) - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID 238 DEMUX 3 [TeleText] send array('B', [1, 0, 238, 3]) + 1/1 TID 00 done! PATready use pmtpid 00e4 for service_id 235b no version filtering 00e4: 02 23 5b 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 1f 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 VIDEO_GET_EVENT - ok VIDEO_GET_EVENT - ok + 1/1 TID 02 done! [eDVBCAService] new service 1:0:19:235B:5B:1:FFFF016A:0:0:0: [eDVBCAService] add demux 3 to slot 0 service 1:0:19:235B:5B:1:FFFF016A:0:0:0: demux 3 mask 08 prevhash 00000000 [eDVBCAService] send 61 bytes 9f 80 32 39 03 23 5b 19 00 1f 01 81 08 ff ff 01 6a 00 5b 00 01 82 02 08 03 84 02 00 e4 09 04 18 01 e0 d9 09 04 18 50 e0 e3 1b 00 d0 00 00 04 00 d1 00 00 06 00 db 00 00 06 00 ee 00 00 recheckPMTHAndlers() recheck 0x25f4c8c 1:0:19:235B:5B:1:FFFF016A:0:0:0: check Slot 0 check Slot 1 check Slot 2 check Slot 3 [eDVBCIInterfaces] gotPMT eventNewProgramInfo 0 0 have 1 video stream(s) (00d0), and 2 audio stream(s) (00d1, 00db), and the pcr pid is 00d0, and the text pid is 00ee decoder state: play, vpid=208, apid=219 [TeleText] serviceInfoChanged [TeleText] TXT PID 238 DEMUX 3 [TeleText] send array('B', [1, 0, 238, 3]) doing version filtering 00e4: 02 23 5b 19 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 +- 1/2 TID 4e [ 83.006000] tda: afc=0 (0 Hz) sdt update done! ++ 2/2 TID 4e done! doing version filtering 0012: 4e 23 5b 09 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [eDVBLocalTimeHandler] Receiver time is 'Thu Jan 1 01:01:25 1970' [eDVBLocalTimeHandler] Transponder time is 'Wed Mar 5 19:40:28 2014' [eDVBLocalTimeHandler] dont have correction.. set Transponder Diff [eDVBLocalTimeHandler] update RTC [eDVBLocalTimeHandler] time_difference is 1394044743 [eDVBLocalTimeHandler] update Linux Time [EPGRefresh] Timer added >)> ProcessRepeated It's now Wed Mar 5 19:40:28 2014 [timer.py] next activation: 1394044859 (in 30097 ms) Repeated: 1 Repeated: 3 Repeated: 7 Repeated: 15 Repeated: 31 Repeated: 63 Repeated: 127 ProcessRepeated localrepeatedbegindate: Wed Mar 5 15:00:00 2014 localbegin: Wed Mar 5 15:00:00 2014 localend: Wed Mar 5 15:00:00 2014 localnow: Wed Mar 5 19:40:29 2014 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 localbegin after addOneDay: Thu Mar 6 15:00:00 2014 localend after addOneDay: Thu Mar 6 15:00:00 2014 ProcessRepeated result Thu Mar 6 15:00:00 2014 Thu Mar 6 15:00:01 2014 It's now Wed Mar 5 19:40:28 2014 [timer.py] next activation: 1394044859 (in 30081 ms) [EPGC] time updated.. start EPG Mainloop before: 1 after: 1 IBTS hide IBTS tunerHide reused converter! reused converter! allocating new converter! Looking for embedded skin allocating new converter! allocating new converter! action -> WizardActions ok unknown action WizardActions/ok! typo in keymap? action -> WizardActions ok Looking for embedded skin [OpenPanel]: GUI OK [EPGC] 131229 events read from /media/cf/epg.dat [EPGC] load 42157 descriptors...action -> WizardActions down action -> DirectionActions downUp unknown action DirectionActions/downUp! typo in keymap? action -> WizardActions ok [OpenPanel] self.zapHistory: [None, 1] action -> WizardActions ok Console: executing in run 0 the command: /usr/script/DNA_cccam_keys.sh finished [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6808263 bytes for cache used [EPGC] next update in 2 sec [EPGC] start caching events(1394044841) no ancillary data in audio stream... abort radiotext pes parser [EPGC] abort non avail viasat reading [EPGC] nownext finished(1394044851) [EPGC] schedule finished(1394044851) action -> WizardActions back action -> WizardActions down action -> DirectionActions downUp unknown action DirectionActions/downUp! typo in keymap? action -> WizardActions ok It's now Wed Mar 5 19:40:59 2014 [EPGRefresh] Not in timespan, ending timer next real activation is Thu Mar 6 15:00:01 2014 [timer.py] next activation: 1394044959 (in 99996 ms) action -> WizardActions down action -> DirectionActions downUp unknown action DirectionActions/downUp! typo in keymap? action -> WizardActions up action -> DirectionActions upUp unknown action DirectionActions/upUp! typo in keymap? [ 129.027000] keys 83 action -> GlobalActions power_down [ 129.227000] keys 87 action -> GlobalActions power_up [ 130.155000] keys 83 action -> GlobalActions power_down [ 130.355000] keys 87 action -> GlobalActions power_up action -> WizardActions ok action -> WizardActions ok nr_read 875 nr_write 2 sum 877 prev_sum 0 hdd was accessed since previous check! [IDLE] 0 600 False action -> WizardActions left unknown action WizardActions/left! typo in keymap? action -> DirectionActions left unknown action DirectionActions/left! typo in keymap? action -> DirectionActions leftUp unknown action DirectionActions/leftUp! typo in keymap? action -> WizardActions ok action -> WizardActions left unknown action WizardActions/left! typo in keymap? action -> DirectionActions left unknown action DirectionActions/left! typo in keymap? action -> DirectionActions leftUp unknown action DirectionActions/leftUp! typo in keymap? action -> WizardActions back [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6825935 bytes for cache used It's now Wed Mar 5 19:41:58 2014 [timer.py] next activation: 1394045018 (in 99998 ms) It's now Wed Mar 5 19:41:58 2014 IBTS Timer Event 3 0 IBTS show [AdvancedMovieSelection] timer state changed event 0 False 3 [AdvancedMovieSelection] cancel timer state changed, no Filename in timer event IBTS Timer Event 3 0 IBTS show [AdvancedMovieSelection] timer state changed event 0 False 3 [AdvancedMovieSelection] cancel timer state changed, no Filename in timer event [timer.py] next activation: 1394045018 (in 99985 ms) IBTS tunerShow IBTS updateNextTimer 0 IBTS tunerHide [EPGC] schedule other finished(1394044931) [EPGC] stop caching events(1394044931) [EPGC] next update in 60 min nr_read 875 nr_write 2 sum 877 prev_sum 877 hdd IDLE! [IDLE] 59.9992330074 600 False action -> WizardActions back action -> WizardActions back It's now Wed Mar 5 19:42:38 2014 next real activation is Thu Mar 6 15:00:01 2014 [timer.py] next activation: 1394045059 (in 99998 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6835276 bytes for cache used child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 76(16) action -> WizardActions ok [OpenPanel]: GUI OK action -> WizardActions back action -> WizardActions back [OpenPanel] - end action -> WizardActions back unknown action WizardActions/back! typo in keymap? action -> WizardActions back nr_read 898 nr_write 5 sum 903 prev_sum 877 hdd was accessed since previous check! [IDLE] 0 600 False [HTTPRootResource].isSessionValid :: created session with id '0' for client with token '9f0c0e4633fa073f61de1991063d735d1b6341d2' [ServiceListReload] reloading lamedb remove ffff0132 0001 004a remove ffff013a 0001 0033 remove ffff0142 0001 005d remove ffff014a 0001 005e remove ffff0152 0001 003b remove ffff015a 0001 004e remove ffff0162 0001 0054 remove ffff016a 0001 005b remove ffff0172 0001 0036 remove ffff017a 0001 004b remove ffff0182 0001 0034 remove ffff018a 0001 0045 remove ffff0192 0001 005f remove ffff019a 0001 0037 remove ffff01a2 0001 003a remove ffff01aa 0001 0065 remove ffff01b2 0001 0060 remove ffff01ca 0001 0038 remove ffff01d2 0001 0039 remove ffff01da 0001 005a remove ffff01e2 0001 0061 remove ffff01ea 0001 0046 remove ffff01f2 0001 0058 remove ffff01fa 0001 004c remove ffff0202 0001 004f remove ffff020a 0001 0047 remove ffff0212 0001 0050 remove ffff021a 0001 0048 remove ffff0222 0001 0053 remove ffff022a 0001 00a9 remove ffff0232 0001 0055 remove ffff023a 0001 0056 remove ffff0242 0001 003c remove ffff0252 0001 0049 remove ffff0262 0001 0051 remove ffff026a 0001 0052 remove ffff0272 0001 0059 remove ffff027a 0001 005c remove ffff0282 0001 0029 remove ffff028a 0001 004d remove ffff0292 0001 003d ---- opening lame channel db reading services (version 4) loaded 441 services [ServiceListReload] reloading userbouquets loading bouquet... /etc/enigma2/bouquets.tv loading bouquet... /etc/enigma2/userbouquet.bef000.tv 0 entries in Bouquet userbouquet.bef000.tv loading bouquet... /etc/enigma2/userbouquet.bef001.tv 0 entries in Bouquet userbouquet.bef001.tv loading bouquet... /etc/enigma2/userbouquet.bef002.tv 0 entries in Bouquet userbouquet.bef002.tv loading bouquet... /etc/enigma2/userbouquet.bef003.tv 0 entries in Bouquet userbouquet.bef003.tv loading bouquet... /etc/enigma2/userbouquet.favourites.tv 0 entries in Bouquet userbouquet.favourites.tv 5 entries in Bouquet bouquets.tv loading bouquet... /etc/enigma2/bouquets.radio loading bouquet... /etc/enigma2/userbouquet.favourites.radio 0 entries in Bouquet userbouquet.favourites.radio 1 entries in Bouquet bouquets.radio screen cleanup! action -> InfobarChannelSelection switchChannelDown reused converter! reused converter! reused converter! reused converter! reused converter! It's now Wed Mar 5 19:43:38 2014 [timer.py] next activation: 1394045118 (in 99997 ms) It's now Wed Mar 5 19:43:38 2014 [timer.py] next activation: 1394045118 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6835258 bytes for cache used action -> OkCancelActions cancel action -> InfobarChannelSelection switchChannelDown reused converter! reused converter! reused converter! reused converter! reused converter! action -> OkCancelActions ok playing 1:0:1:40:4C:1:FFFF01FA:0:0:0: [eDVBCAService] free slot 0 demux 3 for service 1:0:19:235B:5B:1:FFFF016A:0:0:0: [eDVBCAService] free service 1:0:19:235B:5B:1:FFFF016A:0:0:0: recheckPMTHAndlers() [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0[ 292.396000] stop pcr0 on dmx 3 pid:d0 ]) decoder stat[ 292.401000] stop video0 on dmx 3 pid:d0 e: play, vpid=-1[ 292.407000] VIDEO0: stop decode , apid=-1 DEMUX[ 292.411000] VIDEO0: set blank to 1 _STOP - pcr - ok[ 292.416000] bcm_audio: got invalid pts (ret 0)!! DEMUX_STOP - v[ 292.422000] audio: decoder stop ideo - ok VIDEO_STOP - ok AUDIO_STOP - ok DEMUX_STOP - aud[ 292.447000] stop audio on dmx 3 pid:db io - [ 292.452000] audio: xpt stop(3) [ 292.456000] no decoder left in sync handling so disable xpt_sync [ 292.463000] bcm_audio_channel_stop(3) done ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer [TeleText] service started not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0x25f7148 [eDVBLocalTimeHandler] remove channel 0x25f7148 [eEPGCache] remove channel 0x25f7148 (2) preClose allocate channel.. 004c:0001:ffff01fa [eDVBCAService] new channel 0xccecc0! (2)tune tuning to 506000 khz, sr 6900000, fec 0, modulation 5, inversion 2 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:40:4C:1:FFFF01FA:0:0:0: recheckPMTHAndlers() recheck 0x25f4c8c 1:0:1:40:4C:1:FFFF01FA:0:0:0: allocate demux [HbbTV].aitInvalidated [SEC] startTuneTimeout 5000 IBTS show [SEC] setFrontend 1 (2)setting frontend IBTS tunerShow IBTS updateNextTimer 0 (2)fe event: status 0, freq 506000000, inversion off, m_tuning 1 F [ 292.903000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 506000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0xccecc0 running [eDVBLocalTimeHandler] channel 0xccecc0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0xccecc0 running stop release channel timer [EPGC] next update in 2 sec no version filtering 0012: 4e 00 40 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 + 1/1 TID 00 done! PATready no PAT entry found.. start delay doing version filtering 0000: 00 00 00 39 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 [ 293.938000] tda: afc=0 (0 Hz) sdt update done! DVB service failed to tune - error 3 RemovePopup, id = ZapError AddPopup, id = ZapError domain = ZapError [NotificationQueue::popNotification] domain ZapError deferred_callable: False IBTS hide IBTS tunerHide [EPGC] start caching events(1394045037) IBTS show [eDVBLocalTimeHandler] Receiver time is 'Wed Mar 5 19:43:58 2014' [eDVBLocalTimeHandler] Transponder time is 'Wed Mar 5 19:43:58 2014' [eDVBLocalTimeHandler] diff is 0 [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time [eDVBLocalTimeHandler] not changed IBTS tunerShow IBTS updateNextTimer 0 timeout 0012! action -> InfobarChannelSelection zapDown playing 1:0:1:7B:4A:1:FFFF0132:0:0:0: recheckPMTHAndlers() [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) start release channel timer [TeleText] service started not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0xccecc0 [eDVBLocalTimeHandler] remove channel 0xccecc0 [eEPGCache] remove channel 0xccecc0 [EPGC] abort caching events !! (2) preClose allocate channel.. 004a:0001:ffff0132 [eDVBCAService] new channel 0xccecc0! (2)tune tuning to 306000 khz, sr 6900000, fec 9, modulation 5, inversion 0 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:7B:4A:1:FFFF0132:0:0:0: recheckPMTHAndlers() recheck 0x25f4c8c 1:0:1:7B:4A:1:FFFF0132:0:0:0: check Slot 0 check Slot 1 check Slot 2 check Slot 3 allocate demux [HbbTV].aitInvalidated [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (2)setting frontend (2)fe event: status 0, freq 306000000, inversion off, m_tuning 1 F [ 297.292000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 306000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0xccecc0 running [eDVBLocalTimeHandler] channel 0xccecc0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0xccecc0 running stop release channel timer [EPGC] next update in 2 sec no version filtering 0012: 4e 00 7b 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 + 1/1 TID 00 done! PATready use pmtpid 01e4 for service_id 007b no version filtering 01e4: 02 00 7b 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 07 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 +- 1/2 TID 4e + [ 297.372000] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) 1/1 TID 02 done! [eDVBCAServic[ 297.383000] start pcr0 on dmx 3 pid:1d0 index 8 e] new service 1:0:1:7B:4A:1:FFFF0132:0:0:0: [e[ 297.391000] start audio on dmx 3 pid:1d1 DVBCAService] ad[ 297.397000] audio xpt start d demux 3 to slo[ 297.401000] sync is off so we enable it t 0 service 1:0:[ 297.407000] demux-index 3 pcr0pid 1d0 1:7B:4A:1:FFFF01[ 297.411000] using 8 as pcr0 pidchannel 32:0:0:0: demux[ 297.417000] MPEG 3 mask 08 prevh[ 297.420000] audio: streamtype set 0 ash 00000000 [e[ 297.425000] audio: streamtype_set: stop rave DVBCAService] se[ 297.431000] audio: streamtype_set: restart rave nd 51 bytes 9f [ 297.437000] set mute 0 (111) 0 80 32 2f 03 00 7b 07 00 1f 01 81[ 297.443000] audio: decoder start 2 08 ff ff 01 32 00 4a 00 01 82 02 08 03 84 02 01 e4 09 04 18 01 e1 d9 09 04 18 50 e1 e3 02 01 d0 00 00 03 01 d1 00 00 recheckPMTHAndlers() recheck 0x25f4c8c 1:0:1:7B:4A:1:FF[ 297.463000] audio delay 18000 (200 ms) FF0132:0:0:0: c[ 297.467000] total video delay: 18000 (200 ms) heck Slot 0 check Slot 1 check[ 297.474000] VIDEO0: set streamtype! Slot 2 check S[ 297.479000] set MPEG2 lot 3 [eDVBCIIn[ 297.483000] sw rave disabled! terfaces] gotPMT[ 297.488000] video delay 0 (0 ms) eventNewProgra[ 297.493000] total video delay: 0 (0 ms) mInfo 0 0 have 1 video stream(s[ 297.500000] start video0 on dmx 3 pid:1d0 ) (01d0), and 1 [ 297.505000] total video delay: 0 (0 ms) audio stream(s) (01d1), and the [ 297.512000] VIDEO0: start decode pcr pid is 01d0, and the text pid is ffffffff allocate demux disable teletext subtitles PES filter: DMX_SET_PES_FILTER - Invalid argument decoder state: play, vpid=464, apid=465 DMX_SET_PES_FILTER(0x1d0) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x1d1) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux3 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x1d0) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID -1 DEMUX 3 doing version filtering 01e4: 02 00 7b 07 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [ 298.274000] tda: afc=0 (0 Hz) sdt update done! ++ 2/2 TID 4e done! doing version filtering 0012: 4e 00 7b 37 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 action -> InfobarChannelSelection zapUp playing 1:0:1:40:4C:1:FFFF01FA:0:0:0: [eDVBCAService] free slot 0 demux 3 for service 1:0:1:7B:4A:1:FFFF0132:0:0:0: [eDVBCAService] free service 1:0:1:7B:4A:1:FFFF0132:0:0:0: recheckPMTHAndlers() [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) [ 299.279000] stop pcr0 on dmx 3 pid:1d0 decoder state: play, vpid=-1, ap[ 299.284000] stop video0 on dmx 3 pid:1d0 id=-1 DEMUX_STO[ 299.290000] VIDEO0: stop decode P - pcr - ok DE[ 299.295000] VIDEO0: set blank to 1 MUX_STOP - video[ 299.300000] audio: decoder stop - ok VIDEO_STOP - ok AUDIO_STOP - [EPGC] start caching events(1394045042) ok [ 299.327000] stop audio on dmx 3 pid:1d1 DEMUX_STOP - aud[ 299.331000] audio: xpt stop(7) io - [ 299.335000] no decoder left in sync handling so disable xpt_sync [ 299.343000] bcm_audio_channel_stop(7) done ok AUDIO_CONTINUE - ok start release channel timer [TeleText] service started not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0xccecc0 [eDVBLocalTimeHandler] remove channel 0xccecc0 [eEPGCache] remove channel 0xccecc0 [EPGC] abort caching events !! (2) preClose allocate channel.. 004c:0001:ffff01fa [eDVBCAService] new channel 0xccecc0! (2)tune tuning to 506000 khz, sr 6900000, fec 0, modulation 5, inversion 2 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:40:4C:1:FFFF01FA:0:0:0: recheckPMTHAndlers() recheck 0x25f4c8c 1:0:1:40:4C:1:FFFF01FA:0:0:0: allocate demux [HbbTV].aitInvalidated [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (2)setting frontend (2)fe event: status 0, freq 506000000, inversion off, m_tuning 1 F [ 299.456000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 506000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0xccecc0 running [eDVBLocalTimeHandler] channel 0xccecc0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0xccecc0 running stop release channel timer no version filtering 0012: 4e 00 40 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 [EPGC] next update in 2 sec + 1/1 TID 00 done! PATready no PAT entry found.. start delay doing version filtering 0000: 00 00 00 39 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 action -> InfobarChannelSelection zapUp playing 1:0:1:41:47:1:FFFF020A:0:0:0: recheckPMTHAndlers() [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) start release channel timer [TeleText] service started not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0xccecc0 [eDVBLocalTimeHandler] remove channel 0xccecc0 [eEPGCache] remove channel 0xccecc0 (2) preClose allocate channel.. 0047:0001:ffff020a [eDVBCAService] new channel 0xccecc0! (2)tune tuning to 522000 khz, sr 6900000, fec 9, modulation 5, inversion 0 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:41:47:1:FFFF020A:0:0:0: recheckPMTHAndlers() recheck 0x25f4c8c 1:0:1:41:47:1:FFFF020A:0:0:0: check Slot 0 check Slot 1 check Slot 2 check Slot 3 allocate demux [HbbTV].aitInvalidated [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (2)setting frontend (2)fe event: status 0, freq 522000000, inversion off, m_tuning 1 F [ 299.920000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 522000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0xccecc0 running [eDVBLocalTimeHandler] channel 0xccecc0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0xccecc0 running stop release channel timer no version filtering 0012: 4e 00 41 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 [EPGC] next update in 2 sec + 1/1 TID 00 done! PATready use pmtpid 02a4 for service_id 0041 no version filtering 02a4: 02 00 41 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 19 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 +- 1/2 TID 4e + 1/1 TID 02 done! [eDVBCAService] new service 1:0:1:41:47:1:FFFF020A:0:0:0: [eDVBCAService] add demux 3 to slot 0 service 1:0:1:41:47:1:FFFF020A:0:0:0: demux 3 mask 08 prevh[ 300.016000] start pcr0 on dmx 3 pid:290 index 10 ash 00000000 [eDVBCAService] send 61 bytes 9f [ 300.024000] start audio on dmx 3 pid:291 80 32 39 03 00 4[ 300.029000] audio xpt start 1 0f 00 1f 01 81[ 300.034000] sync is off so we enable it 08 ff ff 02 0a [ 300.039000] demux-index 3 pcr0pid 290 00 47 00 01 82 0[ 300.044000] using 10 as pcr0 pidchannel 2 08 03 84 02 02[ 300.050000] MPEG a4 09 04 18 01 [ 300.053000] audio: decoder start 2 e2 99 09 04 18 50 e2 a3 02 02 90 00 00 04 02 91 00 00 06 02 ae 00 00 05 02 b0 00 00 recheckPMTHAndlers() recheck 0x25f4c8c 1:0:1:41:47:1:FFFF020A:0:0:0: check Slot 0 check Slot 1 check S[ 300.074000] video delay 0 (0 ms) lot 2 check Slo[ 300.078000] total video delay: 0 (0 ms) t 3 [eDVBCIInterfaces] gotPMT [ 300.086000] start video0 on dmx 3 pid:290 eventNewProgramI[ 300.091000] total video delay: 0 (0 ms) nfo 0 0 start ait on pid 02b0 [ 300.097000] VIDEO0: start decode no version filtering 02b0: 74 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 have 1 video stream(s) (0290), and 1 audio stream(s) (0291), and the pcr pid is 0290, and the text pid is 02ae allocate demux disable teletext subtitles decoder state: play, vpid=656, apid=657 DMX_SET_PES_FILTER(0x290) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x291) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux3 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x290) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0x2ae) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID 686 DEMUX 3 [TeleText] send array('B', [1, 2, 174, 3]) doing version filtering 02a4: 02 00 41 0f 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 74 done! [eHbbtv] aitChanged pid=0x2b0 [eOipfApplication] Profile 0000, Version 1.1.1 [eOipfApplication] deu:n-tv Digitaltext [eHbbtv] New Application: n-tv Digitaltext (orgid=33, appid=300, urlBase=http://hbbtv.n-tv.de/, initalPath=start.hbbtv, controlCode=2, visibility=2) [eHbbtv] Resolving Application Locator: dvb://current.ait/33.300 [eHbbtv] We have 1 app(s) for service 1.71.65 [eHbbtv] 'dvb://current.ait/33.300' resolved to 'http://hbbtv.n-tv.de/start.hbbtv' [eOipfApplication] Profile 0000, Version 1.1.1 [eOipfApplication] deu:n-tv Startleiste [eHbbtv] New Application: n-tv Startleiste (orgid=33, appid=303, urlBase=http://cdn.digitaltext.n-tv.de/, initalPath=index.html, controlCode=1, visibility=2) [eHbbtv] Resolving Application Locator: dvb://current.ait/33.303 [eHbbtv] We have 2 app(s) for service 1.71.65 [eHbbtv] 'dvb://current.ait/33.303' resolved to 'http://cdn.digitaltext.n-tv.de/index.html' [eOipfApplication] Profile 0000, Version 1.1.1 [eOipfApplication] deu:n-tv Specials (CDN) [eHbbtv] New Application: n-tv Specials (CDN) (orgid=33, appid=302, urlBase=http://cdn.specials.digitaltext.n-tv.de/, initalPath=index.html, controlCode=2, visibility=2) [eHbbtv] Resolving Application Locator: dvb://current.ait/33.302 [eHbbtv] We have 3 app(s) for service 1.71.65 [eHbbtv] 'dvb://current.ait/33.302' resolved to 'http://cdn.specials.digitaltext.n-tv.de/index.html' [eOipfApplication] Profile 0000, Version 1.1.1 [eOipfApplication] deu:n-tv Now Overview [eHbbtv] New Application: n-tv Now Overview (orgid=33, appid=321, urlBase=http://hbbtv.n-tvnow.de/, initalPath=hbbtv/overview.[ 300.943000] tda: afc=0 (0 Hz) php, controlCode=2, visibility=2) [eHbbtv] Resolving Application Locator: dvb://current.ait/33.321 [eHbbtv] We have 4 app(s) for service 1.71.65 [eHbbtv] 'dvb://current.ait/33.321' resolved to 'http://hbbtv.n-tvnow.de/hbbtv/overview.php' [eOipfApplication] Profile 0000, Version 1.1.1 [eOipfApplication] deu:n-tv Now index [eHbbtv] New Application: n-tv Now index (orgid=33, appid=320, urlBase=http://hbbtv.n-tvnow.de/, initalPath=hbbtv/index.php, controlCode=2, visibility=2) [eHbbtv] Resolving Application Locator: dvb://current.ait/33.320 [eHbbtv] We have 5 app(s) for service 1.71.65 [eHbbtv] 'dvb://current.ait/33.320' resolved to 'http://hbbtv.n-tvnow.de/hbbtv/index.php' [HbbTV].readButtonApplicationReady, appid=33.303 [HbbTV].textApplicationReady, appid=33.300 doing version filtering 02b0: 74 00 00 03 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 ++ 2/2 TID 4e done! doing version filtering 0012: 4e 00 41 3f 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 sdt update done! action -> InfobarChannelSelection zapUp playing 1:0:1:67:4D:1:FFFF028A:0:0:0: [eDVBCAService] free slot 0 demux 3 for service 1:0:1:41:47:1:FFFF020A:0:0:0: [eDVBCAService] free service 1:0:1:41:47:1:FFFF020A:0:0:0: recheckPMTHAndlers() [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) decoder state: p[ 301.238000] stop pcr0 on dmx 3 pid:290 lay, vpid=-1, apid=-1 DEMUX_STO[ 301.244000] stop video0 on dmx 3 pid:290 P - pcr - ok DE[ 301.251000] VIDEO0: stop decode MUX_STOP - video[ 301.255000] VIDEO0: set blank to 1 - ok VIDEO_STO[ 301.260000] audio: decoder stop P - ok AUDIO_STOP - ok DEMUX_STOP - a[ 301.283000] stop audio on dmx 3 pid:291 udio - [ 301.289000] audio: xpt stop(8) [ 301.292000] no decoder left in sync handling so disable xpt_sync [ 301.300000] bcm_audio_channel_stop(8) done ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer [TeleText] service started not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0xccecc0 [eDVBLocalTimeHandler] remove channel 0xccecc0 [eEPGCache] remove channel 0xccecc0 (2) preClose allocate channel.. 004d:0001:ffff028a [eDVBCAService] new channel 0xccecc0! (2)tune tuning to 650000 khz, sr 6900000, fec 9, modulation 5, inversion 0 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:67:4D:1:FFFF028A:0:0:0: recheckPMTHAndlers() recheck 0x25f4c8c 1:0:1:67:4D:1:FFFF028A:0:0:0: check Slot 0 check Slot 1 check Slot 2 check Slot 3 allocate demux [HbbTV].aitInvalidated [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (2)setting frontend (2)fe event: status 0, freq 650000000, inversion off, m_tuning 1 F [ 301.413000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 650000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0xccecc0 running [eDVBLocalTimeHandler] channel 0xccecc0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0xccecc0 running stop release channel timer no version filtering 0012: 4e 00 67 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 [EPGC] next update in 2 sec [ 301.468000] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) + 1/1 TID 00 done! PATready u[ 301.479000] start pcr0 on dmx 3 pid:310 index 8 se pmtpid 0315 for service_id 00[ 301.486000] start audio on dmx 3 pid:311 67 no version f[ 301.492000] audio xpt start iltering 0315: [ 301.496000] sync is off so we enable it 02 00 67 00 00 [ 301.502000] demux-index 3 pcr0pid 310 00 mask: ff ff[ 301.507000] using 8 as pcr0 pidchannel ff 00 00 00 mo[ 301.513000] MPEG de: 00 00 00 00[ 301.517000] audio: decoder start 2 00 00 doing version filtering 0000: 00 00 00 33 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 02 done! [eDVBCAService] new service 1:0:1:67:4D:1:FFFF028A:0:0:0: [[ 301.536000] video delay 0 (0 ms) eDVBCAService] a[ 301.541000] total video delay: 0 (0 ms) dd demux 3 to slot 0 service 1:0[ 301.548000] start video0 on dmx 3 pid:310 :1:67:4D:1:FFFF0[ 301.553000] total video delay: 0 (0 ms) 28A:0:0:0: demux 3 mask 08 prev[ 301.560000] VIDEO0: start decode hash 00000000 [eDVBCAService] send 51 bytes 9f 80 32 2f 03 00 67 15 00 1f 01 81 08 ff ff 02 8a 00 4d 00 01 82 02 08 03 84 02 03 15 09 04 18 01 e3 19 09 04 18 50 e3 23 02 03 10 00 00 03 03 11 00 00 recheckPMTHAndlers() recheck 0x25f4c8c 1:0:1:67:4D:1:FFFF028A:0:0:0: check Slot 0 check Slot 1 check Slot 2 check Slot 3 [eDVBCIInterfaces] gotPMT eventNewProgramInfo 0 0 have 1 video stream(s) (0310), and 1 audio stream(s) (0311), and the pcr pid is 0310, and the text pid is ffffffff allocate demux disable teletext subtitles PES filter: DMX_SET_PES_FILTER - Invalid argument decoder state: play, vpid=784, apid=785 DMX_SET_PES_FILTER(0x310) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x311) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux3 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x310) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID -1 DEMUX 3 doing version filtering 0315: 02 00 67 15 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 +- 1/2 TID 4e [ 301.973000] tda: afc=0 (0 Hz) sdt update done! ++ 2/2 TID 4e done! doing version filtering 0012: 4e 00 67 19 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [EPGC] start caching events(1394045046) action -> InfobarShowHideActions hide IBTS hide IBTS tunerHide action -> InfobarChannelSelection switchChannelUp reused converter! reused converter! reused converter! reused converter! reused converter! [eDVBLocalTimeHandler] Receiver time is 'Wed Mar 5 19:44:08 2014' [eDVBLocalTimeHandler] Transponder time is 'Wed Mar 5 19:44:08 2014' [eDVBLocalTimeHandler] diff is 0 [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time [eDVBLocalTimeHandler] not changed [EPGC] abort non avail viasat reading [EPGC] nownext finished(1394045056) [EPGC] schedule finished(1394045056) nr_read 898 nr_write 6 sum 904 prev_sum 903 hdd was accessed since previous check! [IDLE] 0 600 False It's now Wed Mar 5 19:44:19 2014 next real activation is Thu Mar 6 15:00:01 2014 [timer.py] next activation: 1394045159 (in 99998 ms) no ancillary data in audio stream... abort radiotext pes parser [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6835258 bytes for cache used action -> OkCancelActions cancel action -> InfobarChannelSelection switchChannelDown reused converter! reused converter! reused converter! reused converter! reused converter! action -> ChannelSelectBaseActions showFavourites action -> ChannelSelectBaseActions showAllServices eStaticServiceDVBBouquetInformation::getName failed.. getBouquet failed! action -> ChannelSelectBaseActions showFavourites action -> ChannelSelectBaseActions showFavourites action -> ChannelSelectBaseActions showFavourites action -> OkCancelActions ok +- 1/2 TID 4e action -> ChannelSelectBaseActions showFavourites ++ 2/2 TID 4e done! doing version filtering 0012: 4e 00 67 1b 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 action -> OkCancelActions ok action -> ChannelSelectBaseActions showFavourites action -> OkCancelActions ok action -> ChannelSelectBaseActions showFavourites action -> OkCancelActions ok action -> OkCancelActions cancel action -> InfobarChannelSelection zapDown playing -1:0:0:0:0:0:0:0:0:0: [eDVBCAService] free slot 0 demux 3 for service 1:0:1:67:4D:1:FFFF028A:0:0:0: [eDVBCAService] free service 1:0:1:67:4D:1:FFFF028A:0:0:0: recheckPMTHAndlers() [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0])[ 374.049000] stop pcr0 on dmx 3 pid:310 decoder state:[ 374.054000] stop video0 on dmx 3 pid:310 play, vpid=-1, apid=-1 DEMUX_S[ 374.061000] VIDEO0: stop decode TOP - pcr - ok [ 374.066000] VIDEO0: set blank to 1 DEMUX_STOP - video - ok VIDEO_S[ 374.073000] audio: decoder stop TOP - ok AUDIO_STOP - ok DEMUX_STOP - a[ 374.097000] stop audio on dmx 3 pid:311 udio - [ 374.102000] audio: xpt stop(7) [ 374.106000] no decoder left in sync handling so disable xpt_sync [ 374.113000] bcm_audio_channel_stop(7) done ok AUDIO_CONTINUE - ok start release channel timer It's now Wed Mar 5 19:45:18 2014 [timer.py] next activation: 1394045218 (in 99999 ms) It's now Wed Mar 5 19:45:18 2014 [timer.py] next activation: 1394045218 (in 99997 ms) action -> InfobarChannelSelection zapUp playing -1:0:0:0:0:0:0:0:0:0: nr_read 898 nr_write 6 sum 904 prev_sum 904 hdd IDLE! [IDLE] 60.0000920296 600 False action -> InfobarChannelSelection zapUp playing -1:0:0:0:0:0:0:0:0:0: IBTS show IBTS tunerShow IBTS updateNextTimer 0 release cached channel (timer timeout) [eDVBCAService] remove channel 0xccecc0 [eDVBLocalTimeHandler] remove channel 0xccecc0 [eEPGCache] remove channel 0xccecc0 [EPGC] abort caching events !! stop release channel timer (2) preClose action -> InfobarChannelSelection zapUp playing -1:0:0:0:0:0:0:0:0:0: action -> InfobarChannelSelection switchChannelUp IBTS hide reused converter! reused converter! reused converter! reused converter! reused converter! IBTS tunerHide [prepareClose] running mainloop [SEC] lock 0 [SEC] delayed close frontend close frontend 2 [SEC] unlock action -> OkCancelActions cancel IBTS show IBTS tunerShow IBTS updateNextTimer 0 action -> InfobarShowHideActions hide IBTS hide IBTS tunerHide [ServiceListReload] reloading lamedb remove ffff0132 0001 004a remove ffff013a 0001 0033 remove ffff0142 0001 005d remove ffff014a 0001 005e remove ffff0152 0001 003b remove ffff015a 0001 004e remove ffff0162 0001 0054 remove ffff016a 0001 005b remove ffff0172 0001 0036 remove ffff017a 0001 004b remove ffff0182 0001 0034 remove ffff018a 0001 0045 remove ffff0192 0001 005f remove ffff019a 0001 0037 remove ffff01a2 0001 003a remove ffff01aa 0001 0065 remove ffff01b2 0001 0060 remove ffff01ca 0001 0038 remove ffff01d2 0001 0039 remove ffff01da 0001 005a remove ffff01e2 0001 0061 remove ffff01ea 0001 0046 remove ffff01f2 0001 0058 remove ffff01fa 0001 004c remove ffff0202 0001 004f remove ffff020a 0001 0047 remove ffff0212 0001 0050 remove ffff021a 0001 0048 remove ffff0222 0001 0053 remove ffff022a 0001 00a9 remove ffff0232 0001 0055 remove ffff023a 0001 0056 remove ffff0242 0001 003c remove ffff0252 0001 0049 remove ffff0262 0001 0051 remove ffff026a 0001 0052 remove ffff0272 0001 0059 remove ffff027a 0001 005c remove ffff0282 0001 0029 remove ffff028a 0001 004d remove ffff0292 0001 003d ---- opening lame channel db reading services (version 4) loaded 441 services [ServiceListReload] reloading userbouquets loading bouquet... /etc/enigma2/bouquets.tv loading bouquet... /etc/enigma2/userbouquet.bef000.tv 99 entries in Bouquet userbouquet.bef000.tv loading bouquet... /etc/enigma2/userbouquet.bef001.tv 0 entries in Bouquet userbouquet.bef001.tv loading bouquet... /etc/enigma2/userbouquet.bef002.tv 4 entries in Bouquet userbouquet.bef002.tv loading bouquet... /etc/enigma2/userbouquet.bef003.tv 60 entries in Bouquet userbouquet.bef003.tv loading bouquet... /etc/enigma2/userbouquet.favourites.tv 0 entries in Bouquet userbouquet.favourites.tv 5 entries in Bouquet bouquets.tv loading bouquet... /etc/enigma2/bouquets.radio loading bouquet... /etc/enigma2/userbouquet.favourites.radio 0 entries in Bouquet userbouquet.favourites.radio 1 entries in Bouquet bouquets.radio screen cleanup! action -> InfobarChannelSelection switchChannelUp reused converter! reused converter! reused converter! reused converter! reused converter! action -> ChannelSelectBaseActions showAllServices eStaticServiceDVBBouquetInformation::getName failed.. getBouquet failed! action -> ChannelSelectBaseActions showFavourites [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6833682 bytes for cache used action -> OkCancelActions ok playing 1:0:1:94:4A:1:FFFF0132:0:0:0: [TeleText] service started not pauseable. RemovePopup, id = ZapError allocate channel.. 004a:0001:ffff0132 opening frontend 2 [ 408.086000] tda: init complete [eDVBCAService] new channel 0xccecc0! (2)tune tuning to 306000 khz, sr 6900000, fec 9, modulation 5, inversion 0 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:94:4A:1:FFFF0132:0:0:0: recheckPMTHAndlers() recheck 0x28f264c 1:0:1:94:4A:1:FFFF0132:0:0:0: check Slot 0 check Slot 1 check Slot 2 check Slot 3 allocate demux [HbbTV].aitInvalidated [SEC] startTuneTimeout 5000 IBTS show [SEC] setFrontend 1 (2)setting frontend IBTS tunerShow IBTS updateNextTimer 0 (2)fe event: status 0, freq 306000000, inversion off, m_tuning 1 F [ 408.544000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 306000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0xccecc0 running [eDVBLocalTimeHandler] channel 0xccecc0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0xccecc0 running stop release channel timer no version filtering 0012: 4e 00 94 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 [EPGC] next update in 2 sec mask: ff 00 00 00 00 00 mode: 00 [ 408.597000] start pcr0 on dmx 3 pid:210 index 9 00 00 00 00 00 + 1/1 TID 00 done! PATready u[ 408.606000] start audio on dmx 3 pid:211 se pmtpid 0224 f[ 408.611000] audio xpt start or service_id 00[ 408.616000] sync is off so we enable it 94 no version f[ 408.621000] demux-index 3 pcr0pid 210 iltering 0224: [ 408.626000] using 9 as pcr0 pidchannel 02 00 94 00 00 [ 408.632000] MPEG 00 mask: ff ff ff 00 00 00 mo[ 408.636000] audio: decoder start 2 de: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 07 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 02 done! [eDVBCAService] new service 1:0:1:94:4A:1:FF[ 408.657000] video delay 0 (0 ms) FF0132:0:0:0: [[ 408.661000] total video delay: 0 (0 ms) eDVBCAService] add demux 3 to sl[ 408.667000] start video0 on dmx 3 pid:210 ot 0 service 1:0[ 408.673000] total video delay: 0 (0 ms) :1:94:4A:1:FFFF0132:0:0:0: demux 3 mask 08 prev[ 408.681000] VIDEO0: start decode hash 00000000 [eDVBCAService] send 56 bytes 9f 80 32 34 03 00 94 07 00 1f 01 81 08 ff ff 01 32 00 4a 00 01 82 02 08 03 84 02 02 24 09 04 18 01 e2 19 09 04 18 50 e2 23 02 02 10 00 00 03 02 11 00 00 06 02 2e 00 00 recheckPMTHAndlers() recheck 0x28f264c 1:0:1:94:4A:1:FFFF0132:0:0:0: check Slot 0 check Slot 1 check Slot 2 check Slot 3 [eDVBCIInterfaces] gotPMT eventNewProgramInfo 0 0 have 1 video stream(s) (0210), and 1 audio stream(s) (0211), and the pcr pid is 0210, and the text pid is 022e allocate demux disable teletext subtitles decoder state: play, vpid=528, apid=529 DMX_SET_PES_FILTER(0x210) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x211) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux3 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x210) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0x22e) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID 558 DEMUX 3 [TeleText] send array('B', [1, 2, 46, 3]) doing version filtering 0224: 02 00 94 07 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 -+ 1/2 TID 4e [ 409.275000] tda: afc=0 (0 Hz) sdt update done! action -> InfobarChannelSelection switchChannelUp IBTS hide reused converter! reused converter! reused converter! reused converter! reused converter! IBTS tunerHide ++ 2/2 TID 4e done! doing version filtering 0012: 4e 00 94 2f 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [EPGC] start caching events(1394045153) action -> OkCancelActions ok playing 1:0:19:2332:5B:1:FFFF016A:0:0:0: [eDVBCAService] free slot 0 demux 3 for service 1:0:1:94:4A:1:FFFF0132:0:0:0: [eDVBCAService] free service 1:0:1:94:4A:1:FFFF0132:0:0:0: recheckPMTHAndlers() [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) decoder state: p[ 414.118000] stop pcr0 on dmx 3 pid:210 lay, vpid=-1, apid=-1 DEMUX_STO[ 414.126000] stop video0 on dmx 3 pid:210 P - pcr - ok DE[ 414.131000] VIDEO0: stop decode MUX_STOP - video[ 414.136000] VIDEO0: set blank to 1 - ok VIDEO_STOP - ok [ 414.142000] audio: decoder stop AUDIO_STOP - ok DEMUX_STOP - aud[ 414.165000] stop audio on dmx 3 pid:211 io - [ 414.170000] audio: xpt stop(7) [ 414.174000] no decoder left in sync handling so disable xpt_sync [ 414.181000] bcm_audio_channel_stop(7) done ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer [TeleText] service started not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0xccecc0 [eDVBLocalTimeHandler] remove channel 0xccecc0 [eEPGCache] remove channel 0xccecc0 [EPGC] abort caching events !! (2) preClose allocate channel.. 005b:0001:ffff016a [eDVBCAService] new channel 0x6eb3e0c0! (2)tune tuning to 362000 khz, sr 6900000, fec 9, modulation 5, inversion 0 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:19:2332:5B:1:FFFF016A:0:0:0: recheckPMTHAndlers() recheck 0x6eb39b84 1:0:19:2332:5B:1:FFFF016A:0:0:0: allocate demux [HbbTV].aitInvalidated [SEC] startTuneTimeout 5000 IBTS show [SEC] setFrontend 1 (2)setting frontend IBTS tunerShow IBTS updateNextTimer 0 (2)fe event: status 0, freq 362000000, inversion off, m_tuning 1 F [ 414.543000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 362000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0x6eb3e0c0 running [eDVBLocalTimeHandler] channel 0x6eb3e0c0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0x6eb3e0c0 running stop release channel timer no version filtering 0012: 4e 23 32 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 [EPGC] next update in 56 min + 1/1 TID 00 done! PATready use pmtpid 0124 for service_id 2332 no version filtering 0124: 02 23 32 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 1f 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 02 done! [eDVBCAService] new service 1:0:19:2332:5B:1:FFFF016A:0:0:0: [eDVBCAService] add demux 3 to slot 0 service 1:0:19:2332:5B:1:FFFF016A:0:0:0: de[ 414.674000] start pcr0 on dmx 3 pid:110 index 8 mux 3 mask 08 prevhash 00000000[ 414.681000] start audio on dmx 3 pid:11b [eDVBCAService][ 414.686000] audio xpt start send 59 bytes [ 414.691000] sync is off so we enable it 9f 80 32 37 03 2[ 414.696000] demux-index 3 pcr0pid 110 3 32 39 00 13 01[ 414.701000] using 8 as pcr0 pidchannel 81 08 ff ff 01 [ 414.707000] AC3 6a 00 5b 00 01 8[ 414.710000] audio: streamtype set 1 2 02 08 03 84 02[ 414.715000] audio: streamtype_set: stop rave 01 24 1b 01 10 [ 414.720000] decode 00 00 03 01 11 0[ 414.724000] audio: streamtype_set: restart rave 0 00 03 01 12 00[ 414.730000] set mute 0 (111) 0 00 06 01 1b 00 [ 414.735000] audio: decoder start 2 00 06 01 1c 00 00 06 01 2e 00 00 recheckPMTHAndlers() recheck 0x6eb39b84 1:0:19:2332:5B:1:FFFF016A:0:0:0: [eDVBCIInterfaces] gotPMT eventNewProgramInfo 0 0 have 1 video stream(s) (0110),[ 414.756000] video delay 0 (0 ms) and 4 audio str[ 414.761000] total video delay: 0 (0 ms) eam(s) (0111, 01[ 414.767000] VIDEO0: set streamtype! 12, 011b, 011c),[ 414.771000] set H264 and the pcr pid[ 414.775000] sw rave disabled! is 0110, and th[ 414.779000] audio delay 18000 (200 ms) e text pid is 01[ 414.784000] total video delay: 18000 (200 ms) [ 414.790000] ch0: lock 2e allocate dem[ 414.793000] start video0 on dmx 3 pid:110 ux disable tele[ 414.798000] total video delay: 18000 (200 ms) text subtitles decoder state: p[ 414.805000] VIDEO0: start decode lay, vpid=272, apid=283 DMX_SET_PES_FILTER(0x110) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x11b)[ 414.820000] ch0: lock - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(0) - ok AUDIO_PAUSE - ok AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux3 VIDEO_SET_STREAMTYPE 1 - ok DMX_SET_PES_FILTER(0x110) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0x12e) - ttx - ok D[ 414.853000] ch0: lock EMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID 302 DEMUX 3 [TeleText] send array('B', [1, 1, 46, 3]) doing version f[ 414.882000] ch0: lock [ 414.885000] RAP first pts 0xe22ea520, stc 0xe22e9aa4, pcr valid iltering 0124: 02 23 32 39 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [ 414.998000] tda: afc=0 (0 Hz) sdt update done! [ 415.203000] RAP sampling rate info isr (48000) [ 415.203000] eModeChange Isr.. set source_changed_event! [ 415.255000] VIDEO0: first pts 0xe2300f28 [ 415.255000] VIDEO0: resolution changed (1920 1080) VIDEO_GET_EVENT - ok +- 1/2 TID 4e [ 415.415000] enable tsm0 [ 415.805000] VIDEO0: progressive changed (0) VIDEO_GET_EVENT - ok [eDVBLocalTimeHandler] Receiver time is 'Wed Mar 5 19:45:58 2014' [eDVBLocalTimeHandler] Transponder time is 'Wed Mar 5 19:45:58 2014' [eDVBLocalTimeHandler] diff is 0 [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time [eDVBLocalTimeHandler] not changed It's now Wed Mar 5 19:45:59 2014 next real activation is Thu Mar 6 15:00:01 2014 [timer.py] next activation: 1394045259 (in 99997 ms) action -> InfobarChannelSelection zapDown playing 1:0:19:2360:55:1:FFFF0232:0:0:0: [eDVBCAService] free slot 0 demux 3 for service 1:0:19:2332:5B:1:FFFF016A:0:0:0: [eDVBCAService] free service 1:0:19:2332:5B:1:FFFF016A:0:0:0: recheckPMTHAndlers() [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) decoder state: p[ 416.334000] stop pcr0 on dmx 3 pid:110 lay, vpid=-1, apid=-1 DEMUX_STO[ 416.341000] stop video0 on dmx 3 pid:110 P - pcr - ok DEMUX_STOP - video - [ 416.356000] VIDEO0: stop decode ok VIDEO_STOP - [ 416.360000] VIDEO0: set blank to 1 ok AUDIO_STOP - [ 416.366000] audio: decoder stop ok DEMUX_STOP - a[ 416.390000] stop audio on dmx 3 pid:11b udio - [ 416.395000] audio: xpt stop(6) [ 416.399000] no decoder left in sync handling so disable xpt_sync [ 416.405000] bcm_audio_channel_stop(6) done ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer [TeleText] service started not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0x6eb3e0c0 [eDVBLocalTimeHandler] remove channel 0x6eb3e0c0 [eEPGCache] remove channel 0x6eb3e0c0 (2) preClose allocate channel.. 0055:0001:ffff0232 [eDVBCAService] new channel 0xccecc0! (2)tune tuning to 562000 khz, sr 6900000, fec 0, modulation 5, inversion 2 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:19:2360:55:1:FFFF0232:0:0:0: recheckPMTHAndlers() recheck 0x28661a4 1:0:19:2360:55:1:FFFF0232:0:0:0: allocate demux [HbbTV].aitInvalidated [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (2)setting frontend (2)fe event: status 0, freq 562000000, inversion off, m_tuning 1 F [ 416.784000] tda: afc=0 (0 Hz) (2)fe event: status 1f, freq 562000000, inversion off, m_tuning 1 N OURSTATE: ok [eDVBCAService] channel 0xccecc0 running [eDVBLocalTimeHandler] channel 0xccecc0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0xccecc0 running stop release channel timer [EPGC] next update in 2 sec no version filtering 0012: 4e 23 60 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 + 1/1 TID 00 done! PATready use pmtpid 0124 for service_id 2360 no version filtering 0124: 02 23 60 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 03 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 02 done! [[ 416.896000] start pcr0 on dmx 3 pid:110 index 8 eDVBCAService] new service 1:0:1[ 416.902000] start audio on dmx 3 pid:11b 9:2360:55:1:FFFF[ 416.908000] audio xpt start 0232:0:0:0: [eD[ 416.912000] sync is off so we enable it VBCAService] add[ 416.918000] demux-index 3 pcr0pid 110 demux 3 to slot[ 416.924000] using 8 as pcr0 pidchannel 0 service 1:0:1[ 416.929000] AC3 9:2360:55:1:FFFF[ 416.933000] audio: decoder start 0 0232:0:0:0: demux 3 mask 08 prevhash 00000000 [eDVBCAService] send 59 bytes 9f 80 32 37 03 23 60 0f 00 13 01 81 08 ff ff 02 32 00 55 00 01 82 02 08 03 84 02 01 24 1b 01 10 00 00 03 01 11 00 00 03 01 12 00 [ 416.954000] audio delay 18000 (200 ms) 00 06 01 1b 00 0[ 416.959000] total video delay: 18000 (200 ms) 0 06 01 1c 00 00 06 01 2e 00 00 [ 416.967000] start video0 on dmx 3 pid:110 recheckPMTHAnd[ 416.973000] total video delay: 18000 (200 ms) lers() recheck 0x28661a4 1:0:19[ 416.980000] VIDEO0: start decode :2360:55:1:FFFF0232:0:0:0: [eDVBCIInterfaces] gotPMT eventNewProgramInfo 0 0 have 1 video stream(s) (0110), and 4 audio stream(s) (0111, 0112, 011b, 011c), a[ 416.998000] ch0: lock nd the pcr pid is 0110, and the text pid is 012e allocate demux disable teletext subtitles decoder state: play, vpid=272, apid=283 DMX_SET_PES_FILTER(0x110) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x11b) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(0) - ok AUDIO_PAUSE - o[ 417.027000] ch0: lock [ 417.030000] RAP first pts 0xe2319682, stc 0xe2318c52, pcr valid k AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux3 VIDEO_SET_STREAMTYPE 1 - ok DMX_SET_PES_FILTER(0x110) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0x12e) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID 302 DEMUX 3 [TeleText] send array('B', [1, 1, 46, 3]) doing version filtering 0124: 02 23 60 0f 00 00 mask: ff ff f[ 417.085000] tda: afc=0 (0 Hz) f 3f 00 00 mode: 00 00 00 3e 00 00 sdt update done! -+ 1/2 TID 4e [ 417.346000] RAP sampling rate info isr (48000) [ 417.346000] eModeChange Isr.. set source_changed_event! [ 417.405000] VIDEO0: first pts 0xe2332094 [ 417.559000] enable tsm0 ++ 2/2 TID 4e done! doing version filtering 0012: 4e 23 60 11 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [EPGC] start caching events(1394045161) IBTS hide IBTS tunerHide [EPGC] abort non avail viasat reading [eDVBLocalTimeHandler] Receiver time is 'Wed Mar 5 19:46:08 2014' [eDVBLocalTimeHandler] Transponder time is 'Wed Mar 5 19:46:08 2014' [eDVBLocalTimeHandler] diff is 0 [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time [eDVBLocalTimeHandler] not changed [EPGC] nownext finished(1394045171) [EPGC] schedule finished(1394045171) nr_read 898 nr_write 6 sum 904 prev_sum 904 hdd IDLE! [IDLE] 119.999927044 600 False no ancillary data in audio stream... abort radiotext pes parser [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6833682 bytes for cache used It's now Wed Mar 5 19:46:58 2014 [timer.py] next activation: 1394045318 (in 99997 ms) It's now Wed Mar 5 19:46:58 2014 [timer.py] next activation: 1394045318 (in 99995 ms) nr_read 901 nr_write 9 sum 910 prev_sum 904 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] schedule other finished(1394045258) [EPGC] stop caching events(1394045258) [EPGC] next update in 60 min It's now Wed Mar 5 19:47:39 2014 next real activation is Thu Mar 6 15:00:01 2014 [timer.py] next activation: 1394045359 (in 99996 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6833682 bytes for cache used nr_read 901 nr_write 9 sum 910 prev_sum 910 hdd IDLE! [IDLE] 59.9976160526 600 False It's now Wed Mar 5 19:48:38 2014 [timer.py] next activation: 1394045418 (in 99998 ms) It's now Wed Mar 5 19:48:38 2014 [timer.py] next activation: 1394045418 (in 99995 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 6833682 bytes for cache used nr_read 901 nr_write 12 sum 913 prev_sum 910 hdd was accessed since previous check! [IDLE] 0 600 False It's now Wed Mar 5 19:49:19 2014 next real activation is Thu Mar 6 15:00:01 2014 [timer.py] next activation: 1394045459 (in 99998 ms)