Mon Jul 11 16:21:33 2011 Jul 6 2011 moritz.venn@freaque.net Kerni-HD1R2/skin.xml 2011-07-06 experimental dm800 console=ttyS0,115200 root=/dev/mtdblock3 rootfstype=jffs2 rw mem=128M NIM Socket 0: url=http://www.dreamboxupdate.com/ catalog=http://www.dreamboxupdate.com/ ]]> InfobarMenuActions mainMenu loading mainmenu XML... TimerEdit TimerEditList PluginBrowser PluginBrowser reused converter! reused converter! allocating new converter! allocating new converter! action -> OkCancelActions ok okbuttonClick reused converter! reused converter! action -> WizardActions ok [EPGRefresh] Stopping Timer reused converter! reused converter! ERROR reading PES (fd=32) - Value too large for defined data type poll: unhandled POLLERR/HUP/NVAL for fd 32(8) no ancillary data in audio stream... abort radiotext pes parser action -> ColorActions blue reused converter! reused converter! action -> SetupActions cancel unknown action SetupActions/cancel! typo in keymap? action -> SetupActions cancel action -> SetupActions cancel unknown action SetupActions/cancel! typo in keymap? action -> SetupActions cancel [EPGRefresh] Timer added >)> ProcessRepeated It's now Mon Jul 11 16:20:24 2011 [timer.py] next activation: 1310394055 (in 30510 ms) Repeated: 1 Repeated: 3 Repeated: 7 Repeated: 15 Repeated: 31 Repeated: 63 Repeated: 127 ProcessRepeated localrepeatedbegindate: Mon Jul 11 02:00:00 2011 localbegin: Mon Jul 11 02:00:00 2011 localend: Mon Jul 11 02:00:00 2011 localnow: Mon Jul 11 16:20:25 2011 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 localbegin after addOneDay: Tue Jul 12 02:00:00 2011 localend after addOneDay: Tue Jul 12 02:00:00 2011 ProcessRepeated result Tue Jul 12 02:00:00 2011 Tue Jul 12 02:00:01 2011 It's now Mon Jul 11 16:20:24 2011 [timer.py] next activation: 1310394055 (in 30425 ms) action -> WizardActions back action -> OkCancelActions cancel action -> InfobarChannelSelection switchChannelDown reused converter! reused converter! reused converter! action -> ChannelSelectBaseActions prevBouquet action -> ChannelSelectBaseActions prevBouquet action -> OkCancelActions ok playing 1:0:1:7FF:449:35:C00000:0:0:0: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:2B66:3F3:1:C00000:0:0:0: [eDVBCAService] free service 1:0:19:2B66:3F3:1:C00000:0:0:0: decoder state: play, vpid=-1, apid=-1 DEMUX_STOP - pcr - ok DEMUX_STOP - video - ok VIDEO_STOP - ok AUDIO_STOP - ok DEMUX_STOP - audio - ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0x1899020 [eDVBLocalTimerHandler] remove channel 0x1899020 [eEPGCache] remove channel 0x1899020 allocate channel.. 0449:0035 [eDVBCAService] new channel 0x1899020! (0)tune RotorCmd ffffffff, lastRotorCmd ffffffff prepare_sat System 0 Freq 12343500 Pol 0 SR 27500000 INV 2 FEC 3 orbpos 192 system 0 modulation 1 pilot 2, rolloff 0 tuning to 1743 mhz OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:7FF:449:35:C00000:0:0:0: allocate demux resolve: resolve ${sysconfdir}/scan_tp_valid_check.py resolve: -> /etc/scan_tp_valid_check.py [SEC] set static current limiting set sequence pos 3 set sequence pos 7 [SEC] invalidate current switch params [SEC] sendDiseqc: e01038f3 [SEC] sleep 50ms set sequence pos 3 [SEC] setTone 1 [SEC] sleep 10ms [SEC] update current switch params [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 setting frontend 0 [SEC] sleep 500ms (0)fe event: status 0, inversion off, m_tuning 1 (0)fe event: status 1, inversion off, m_tuning 2 (0)fe event: status 3, inversion off, m_tuning 3 (0)fe event: status 1f, inversion off, m_tuning 4 OURSTATE: ok [eDVBCAService] channel 0x1899020 running [eDVBLocalTimerHandler] channel 0x1899020 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 0x1899020 running stop release channel timer no version filtering 0012: 4e 07 ff 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 eventNewProgramInfo 0 0 have 1 video stream(s) (020c), and 1 audio stream(s) (007e), and the pcr pid is 1ffe, and the text pid is ffffffff allocate demux disable teletext subtitles PES filter: DMX_SET_PES_FILTER - Invalid argument decoder state: play, vpid=524, apid=126 DMX_SET_PES_FILTER(0x1ffe) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x7e) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - [EPGC] next update in 2 sec ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux0 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x20c) - 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. +- 1/2 TID 4e + 1/1 TID 00 done! PATready use pmtpid 07ff for service_id 07ff no version filtering 07ff: 02 07 ff 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 13 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 02 done! eventNewProgramInfo 0 0 have 1 video stream(s) (020c), and 1 audio stream(s) (007e), and the pcr pid is 1ffe, and the text pid is ffffffff PES filter: DMX_SET_PES_FILTER - Invalid argument decoder state: play, vpid=524, apid=126 VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok [eDVBCAService] new service 1:0:1:7FF:449:35:C00000:0:0:0: [eDVBCAService] add demux 0 to slot 0 service 1:0:1:7FF:449:35:C00000:0:0:0: [eDVBCIInterfaces] gotPMT demux 0 mask 01 prevhash 00000000 [eDVBCAService] send 103 bytes 9f 80 32 63 03 07 ff 01 00 53 01 81 08 00 c0 00 00 04 49 00 35 82 02 01 00 84 02 07 ff 09 04 17 02 e7 80 09 04 18 33 e7 81 09 04 09 c4 e7 82 09 04 0d 95 eb d8 09 04 06 48 eb ec 09 04 0d 05 eb c4 09 11 01 00 e7 14 00 6a ff ff e0 00 00 00 00 00 00 2a eb 09 07 18 17 e7 14 02 00 6a 02 02 0c 00 00 04 00 7e 00 00 doing version filtering 07ff: 02 07 ff 01 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [SEC] set dynamic current limiting [eDVBLocalTimerHandler] diff is 1 [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time [eDVBLocalTimerHandler] update RTC [eDVBLocalTimerHandler] time update to 16:20:36 [eDVBLocalTimerHandler] m_time_difference is 1 [eDVBLocalTimerHandler] set Linux Time [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 72018 bytes for cache used ++ 2/2 TID 4e done! doing version filtering 0012: 4e 07 ff 0b 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 sdt update done! [EPGC] start caching events(1310394038) VIDEO_GET_EVENT - ok VIDEO_GET_EVENT - ok VIDEO_GET_EVENT - ok nr_read 113 nr_write 53 sum 166 prev_sum 166 hdd IDLE! [IDLE] 35940.9998629 600 True [EPGC] abort non avail schedule other reading [EPGC] abort non avail viasat reading [EPGC] nownext finished(1310394053) action -> InfobarShowHideActions toggleShow no ancillary data in audio stream... abort radiotext pes parser It's now Mon Jul 11 16:20:56 2011 [EPGRefresh] Not in timespan, ending timer next real activation is Tue Jul 12 02:00:01 2011 [timer.py] next activation: 1310394156 (in 99983 ms) [TVCharts] Status Update ... action -> InfobarShowHideActions toggleShow action -> SetupActions ok playing 1:0:19:2B5C:3F3:1:C00000:0:0:0: [eDVBCAService] free slot 0 demux 0 for service 1:0:1:7FF:449:35:C00000:0:0:0: [eDVBCAService] free service 1:0:1:7FF:449:35:C00000:0:0:0: decoder state: play, vpid=-1, apid=-1 DEMUX_STOP - pcr - ok DEMUX_STOP - video - ok VIDEO_STOP - ok AUDIO_STOP - ok DEMUX_STOP - audio - ok AUDIO_CONTINUE - ok start release channel timer not pauseable. RemovePopup, id = ZapError [eDVBCAService] remove channel 0x1899020 [eDVBLocalTimerHandler] remove channel 0x1899020 [eEPGCache] remove channel 0x1899020 [EPGC] abort caching events !! allocate channel.. 03f3:0001 [eDVBCAService] new channel 0x1899020! (0)tune RotorCmd ffffffff, lastRotorCmd ffffffff prepare_sat System 1 Freq 11361750 Pol 0 SR 22000000 INV 2 FEC 2 orbpos 192 system 1 modulation 2 pilot 2, rolloff 0 tuning to 1611 mhz OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:19:2B5C:3F3:1:C00000:0:0:0: allocate demux resolve: resolve ${sysconfdir}/scan_tp_valid_check.py resolve: -> /etc/scan_tp_valid_check.py [SEC] set static current limiting [SEC] setTone 0 [SEC] sleep 25ms set sequence pos 7 [SEC] invalidate current switch params [SEC] sendDiseqc: e01038f2 [SEC] sleep 50ms set sequence pos 3 set sequence pos 3 [SEC] update current switch params [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 setting frontend 0 [SEC] sleep 500ms (0)fe event: status 0, inversion off, m_tuning 1 (0)fe event: status 1, inversion off, m_tuning 2 (0)fe event: status 1f, inversion off, m_tuning 3 OURSTATE: ok [eDVBCAService] channel 0x1899020 running [eDVBLocalTimerHandler] channel 0x1899020 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 0x1899020 running stop release channel timer no version filtering 0012: 4e 2b 5c 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 eventNewProgramInfo 0 0 have 1 video stream(s) (177a), and 1 audio stream(s) (1786), and the pcr pid is 177a, and the text pid is 178e allocate demux disable teletext subtitles decoder state: play, vpid=6010, apid=6022 DMX_SET_PES_FILTER(0x177a) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x1786) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(0) - ok AUDIO_PAUSE - ok AUDIO_PLAY - [EPGC] next update in 1 min ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux0 VIDEO_SET_STREAMTYPE 1 - ok DMX_SET_PES_FILTER(0x177a) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0x178e) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. + 1/1 TID 00 done! PATready use pmtpid 1770 for service_id 2b5c no version filtering 1770: 02 2b 5c 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 15 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 [SEC] set dynamic current limiting + 1/1 TID 02 done! eventNewProgramInfo 0 0 have 1 video stream(s) (177a), and 3 audio stream(s) (1784, 1785, 1786), and the pcr pid is 177a, and the text pid is 178e decoder state: play, vpid=6010, apid=6022 [eDVBCAService] new service 1:0:19:2B5C:3F3:1:C00000:0:0:0: [eDVBCAService] add demux 0 to slot 0 service 1:0:19:2B5C:3F3:1:C00000:0:0:0: [eDVBCIInterfaces] gotPMT demux 0 mask 01 prevhash 00000000 [eDVBCAService] send 69 bytes 9f 80 32 41 03 2b 5c 05 00 13 01 81 08 00 c0 00 00 03 f3 00 01 82 02 01 00 84 02 17 70 0b 08 7b 00 00 0b 08 7c 00 00 1b 17 7a 00 00 03 17 84 00 00 03 17 85 00 00 06 17 86 00 00 06 17 8e 00 00 05 17 b6 00 00 doing version filtering 1770: 02 2b 5c 05 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 -+ 1/2 TID 4e VIDEO_GET_EVENT - ok VIDEO_GET_EVENT - ok sdt update done! ++ 2/2 TID 4e done! doing version filtering 0012: 4e 2b 5c 3d 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 VIDEO_GET_EVENT - ok action -> InfobarMenuActions mainMenu loading mainmenu XML... TimerEdit TimerEditList PluginBrowser PluginBrowser reused converter! reused converter! allocating new converter! allocating new converter! action -> OkCancelActions ok okbuttonClick reused converter! reused converter! [eDVBLocalTimerHandler] diff is -1 [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time [eDVBLocalTimerHandler] update RTC [eDVBLocalTimerHandler] time update to 16:21:29 [eDVBLocalTimerHandler] m_time_difference is -1 [eDVBLocalTimerHandler] set Linux Time [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 223027 bytes for cache used action -> WizardActions ok [EPGRefresh] Stopping Timer reused converter! reused converter! It's now Mon Jul 11 16:21:31 2011 next real activation is Mon Jul 11 18:46:40 2011 [timer.py] next activation: 1310394191 (in 98997 ms) It's now Mon Jul 11 16:21:32 2011 [timer.py] next activation: 1310394192 (in 99990 ms) ERROR reading PES (fd=40) - Value too large for defined data type poll: unhandled POLLERR/HUP/NVAL for fd 40(8) action -> ColorActions yellow [EPGRefresh] Forcing start of EPGRefresh [EPGRefresh] About to start refreshing EPG [AutoTimer] No changes in configuration, won't parse [EPGRefresh] Services we're going to scan: , , , , , , , , , , , , , , , , , , , [EPGRefresh] Adapter is not able to run in background or not available, falling back to MainPictureAdapter Traceback (most recent call last): File "/usr/lib/enigma2/python/Components/ActionMap.py", line 46, in action res = self.actions[action]() File "/usr/lib/enigma2/python/Plugins/Extensions/EPGRefresh/EPGRefreshConfiguration.py", line 143, in forceRefresh epgrefresh.forceRefresh(self.session) File "/usr/lib/enigma2/python/Plugins/Extensions/EPGRefresh/EPGRefresh.py", line 147, in forceRefresh self.prepareRefresh() File "/usr/lib/enigma2/python/Plugins/Extensions/EPGRefresh/EPGRefresh.py", line 251, in prepareRefresh self.refreshAdapter.prepare() File "/usr/lib/enigma2/python/Plugins/Extensions/EPGRefresh/MainPictureAdapter.py", line 18, in prepare self.previousService = session.nav.getCurrentlyPlayingServiceReference() NameError: global name 'session' is not defined (PyObject_CallObject(>,('ColorActions', 'yellow')) failed) ]]>