-- Logs begin at Do 1970-01-01 01:00:00 CET. --
Aug 27 22:31:39 dm900 enigma2[247]:   File "/usr/lib/enigma2/python/e2reactor.py", line 55, in _read
Aug 27 22:31:39 dm900 enigma2[247]:     why = w.doRead()
Aug 27 22:31:39 dm900 enigma2[247]: --- <exception caught here> ---
Aug 27 22:31:39 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/twisted/internet/udp.py", line 248, in doRead
Aug 27 22:31:39 dm900 enigma2[247]:     self.protocol.datagramReceived(data, addr)
Aug 27 22:31:39 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/coherence/upnp/core/ssdp.py", line 99, in datagramReceived
Aug 27 22:31:39 dm900 enigma2[247]:     self.notifyReceived(headers, (host, port))
Aug 27 22:31:39 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/coherence/upnp/core/ssdp.py", line 161, in notifyReceived
Aug 27 22:31:39 dm900 enigma2[247]:     self.info('Notification from (%s,%d) for %s' % (host, port, headers['nt']))
Aug 27 22:31:39 dm900 enigma2[247]: exceptions.KeyError: 'nt'
Aug 27 22:31:45 dm900 enigma2[247]: action ->  WizardActions up
Aug 27 22:31:45 dm900 enigma2[247]: unknown action WizardActions/up! typo in keymap?
Aug 27 22:31:46 dm900 enigma2[247]: action ->  WizardActions ok
Aug 27 22:31:46 dm900 enigma2[247]: create buffer for widget 329 x 201
Aug 27 22:31:49 dm900 enigma2[247]: main thread is non-idle! display spinner!
Aug 27 22:31:50 dm900 enigma2[247]: [eFileMonitor] 1 events queued
Aug 27 22:31:55 dm900 enigma2[247]: create buffer for widget 494 x 201
Aug 27 22:31:55 dm900 enigma2[247]: nr_read 231 nr_write 1
Aug 27 22:31:55 dm900 enigma2[247]: sum 232 prev_sum 232
Aug 27 22:31:55 dm900 enigma2[247]: hdd IDLE!
Aug 27 22:31:55 dm900 enigma2[247]: [IDLE] 454.794847012 300 True
Aug 27 22:31:55 dm900 enigma2[247]: [eFileMonitor] File {/tmp/gbox.ver} has been created
Aug 27 22:31:55 dm900 enigma2[247]: ERROR reading PES (fd=101) - Value too large for defined data type
Aug 27 22:31:55 dm900 enigma2[247]: poll: unhandled POLLERR/HUP/NVAL for fd 101(8)
Aug 27 22:31:55 dm900 enigma2[247]: illegal data unit length 0
Aug 27 22:31:55 dm900 enigma2[247]: 535538 ms latency per 1000 blits (1,867281 fps)
Aug 27 22:31:58 dm900 enigma2[247]: It's now  So 27 Aug 2017 22:31:57 CEST
Aug 27 22:31:58 dm900 enigma2[247]: [EPGRefreshTimer] next real activation is So 27 Aug 2017 22:33:41 CEST
Aug 27 22:31:58 dm900 enigma2[247]: [timer.py] next activation: 1503866018 (in 99998 ms)
Aug 27 22:31:58 dm900 enigma2[247]: action ->  MsgBoxActions cancel
Aug 27 22:31:59 dm900 enigma2[247]: action ->  MsgBoxActions cancel
Aug 27 22:32:00 dm900 enigma2[247]: action ->  WizardActions back
Aug 27 22:32:01 dm900 enigma2[247]: action ->  SetupActions cancel
Aug 27 22:32:02 dm900 enigma2[247]: action ->  SetupActions cancel
Aug 27 22:32:02 dm900 enigma2[247]: action ->  OkCancelActions cancel
Aug 27 22:32:04 dm900 enigma2[247]: action ->  InfobarChannelSelection zapDown
Aug 27 22:32:04 dm900 enigma2[247]: playing 1:0:19:D73F:227:270F:FFFF0000:0:0:0:
Aug 27 22:32:04 dm900 enigma2[247]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:814E:14B:270F:FFFF0000:0:0:0:
Aug 27 22:32:04 dm900 enigma2[247]: [eDVBCAService] free service 1:0:19:814E:14B:270F:FFFF0000:0:0:0:
Aug 27 22:32:04 dm900 enigma2[247]: [eDBusInterface::Private::sendEvent 0
Aug 27 22:32:04 dm900 enigma2[247]: FIXMEE unknown frontend type in FrontendInfo getValue Converter return 0 (SAT) None
Aug 27 22:32:04 dm900 enigma2[247]: [TeleText] service stopped
Aug 27 22:32:04 dm900 enigma2[247]: [TeleText] send array('B', [1, 0, 0, 0])
Aug 27 22:32:04 dm900 enigma2[247]: disable dvb subtitles
Aug 27 22:32:04 dm900 enigma2[247]: disable teletext subtitles page ffffffffffffffff (und)
Aug 27 22:32:04 dm900 enigma2[247]: decoder state: play, vpid=-1, apid=-1
Aug 27 22:32:04 dm900 enigma2[247]: DEMUX_STOP - pcr - ok
Aug 27 22:32:04 dm900 enigma2[247]: DEMUX_STOP - video - ok
Aug 27 22:32:04 dm900 enigma2[247]: VIDEO_STOP - ok
Aug 27 22:32:04 dm900 enigma2[247]: AUDIO_STOP - ok
Aug 27 22:32:04 dm900 enigma2[247]: DEMUX_STOP - audio - ok
Aug 27 22:32:04 dm900 enigma2[247]: AUDIO_CONTINUE - ok
Aug 27 22:32:04 dm900 enigma2[247]: DEMUX_STOP - ttx - ok
Aug 27 22:32:04 dm900 enigma2[247]: start release channel timer
Aug 27 22:32:04 dm900 enigma2[247]: [Picon Renderer] found /picons/piconHD/1_0_19_D73F_227_270F_FFFF0000_0_0_0.png
Aug 27 22:32:04 dm900 enigma2[247]: [TeleText] service started
Aug 27 22:32:04 dm900 enigma2[247]: create buffer for widget 1280 x 720
Aug 27 22:32:04 dm900 enigma2[247]: not pauseable.
Aug 27 22:32:04 dm900 enigma2[247]: RemovePopup, id = ZapError
Aug 27 22:32:04 dm900 enigma2[247]: [eDVBCAService] remove channel 0x1782340
Aug 27 22:32:04 dm900 enigma2[247]: [eDVBLocalTimeHandler] remove channel 0x1782340
Aug 27 22:32:04 dm900 enigma2[247]: [EPGC] remove channel 0x1782340
Aug 27 22:32:04 dm900 enigma2[247]: [    0] preClose
Aug 27 22:32:04 dm900 enigma2[247]: allocate channel.. 0227:270f:ffff0000
Aug 27 22:32:04 dm900 enigma2[247]: [eDVBCAService] new channel 0x1782340!
Aug 27 22:32:04 dm900 enigma2[247]: [    0] tune type 2
Aug 27 22:32:04 dm900 enigma2[247]: [    0] DVB-C tuning to 138000 khz, sr 6900000, fec 0, modulation 5, inversion 2
Aug 27 22:32:04 dm900 enigma2[247]: OURSTATE: tuning
Aug 27 22:32:04 dm900 enigma2[247]: allocate Channel: res 0
Aug 27 22:32:04 dm900 enigma2[247]: allocate demux
Aug 27 22:32:04 dm900 enigma2[247]: setInputSource input0 to A1
Aug 27 22:32:04 dm900 enigma2[247]: have 1 video stream(s) (0215), and 1 audio stream(s) (0216), and the pcr pid is 0215, and the text pid is 021a
Aug 27 22:32:04 dm900 enigma2[247]: allocate demux
Aug 27 22:32:04 dm900 enigma2[247]: disable teletext subtitles page ffffffffffffffff (und)
Aug 27 22:32:04 dm900 enigma2[247]: [SEC-0] update oparm
Aug 27 22:32:04 dm900 enigma2[247]: [SEC-0] startTuneTimeout 5000
Aug 27 22:32:04 dm900 enigma2[247]: [SEC-0] setFrontend 1
Aug 27 22:32:04 dm900 enigma2[247]: [    0] setting frontend
Aug 27 22:32:04 dm900 enigma2[247]: decoder state: play, vpid=533, apid=534
Aug 27 22:32:04 dm900 enigma2[247]: DMX_SET_PES_FILTER(0x215) - pcr - ok
Aug 27 22:32:04 dm900 enigma2[247]: DEMUX_START - pcr - ok
Aug 27 22:32:04 dm900 enigma2[247]: DMX_SET_PES_FILTER(0x216) - audio - ok
Aug 27 22:32:04 dm900 enigma2[247]: DEMUX_START - audio - ok
Aug 27 22:32:04 dm900 enigma2[247]: AUDIO_SET_BYPASS(0) - ok
Aug 27 22:32:04 dm900 enigma2[247]: AUDIO_PAUSE - ok
Aug 27 22:32:04 dm900 enigma2[247]: AUDIO_PLAY - ok
Aug 27 22:32:04 dm900 enigma2[247]: Video Device: /dev/dvb/adapter0/video0
Aug 27 22:32:04 dm900 enigma2[247]: demux device: /dev/dvb/adapter0/demux0
Aug 27 22:32:04 dm900 enigma2[247]: VIDEO_SET_STREAMTYPE 1 - ok
Aug 27 22:32:04 dm900 enigma2[247]: DMX_SET_PES_FILTER(0x215) - video - ok
Aug 27 22:32:04 dm900 enigma2[247]: DEMUX_START - video - ok
Aug 27 22:32:04 dm900 enigma2[247]: VIDEO_FREEZE - ok
Aug 27 22:32:04 dm900 enigma2[247]: VIDEO_PLAY - ok
Aug 27 22:32:04 dm900 enigma2[247]: DMX_SET_PES_FILTER(0x21a) - ttx - ok
Aug 27 22:32:04 dm900 enigma2[247]: DEMUX_START - ttx - ok
Aug 27 22:32:04 dm900 enigma2[247]: VIDEO_SLOWMOTION(0) - ok
Aug 27 22:32:04 dm900 enigma2[247]: VIDEO_FAST_FORWARD(0) - ok
Aug 27 22:32:04 dm900 enigma2[247]: VIDEO_CONTINUE - ok
Aug 27 22:32:04 dm900 enigma2[247]: AUDIO_CONTINUE - ok
Aug 27 22:32:04 dm900 enigma2[247]: not pauseable.
Aug 27 22:32:04 dm900 enigma2[247]: [HbbTV].aitInvalidated
Aug 27 22:32:04 dm900 enigma2[247]: [    0] fe event: status 0, freq 138000000, inversion off, m_tuning 1 F
Aug 27 22:32:04 dm900 enigma2[247]: [    0] fe event: status 1f, freq 138000000, inversion off, m_tuning 1 N
Aug 27 22:32:04 dm900 enigma2[247]: OURSTATE: ok
Aug 27 22:32:04 dm900 enigma2[247]: [eDVBCAService] channel 0x1782340 running
Aug 27 22:32:04 dm900 enigma2[247]: [eDVBLocalTimeHandler] channel 0x1782340 running
Aug 27 22:32:04 dm900 enigma2[247]: no version filtering
Aug 27 22:32:04 dm900 enigma2[247]: 0014:  70 00 00 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: mask:  fc 00 00 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: mode:  00 00 00 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: [EPGC] channel 0x1782340 running
Aug 27 22:32:04 dm900 enigma2[247]: [EPGC] next update in 2 sec
Aug 27 22:32:04 dm900 enigma2[247]: stop release channel timer
Aug 27 22:32:04 dm900 enigma2[247]: no version filtering
Aug 27 22:32:04 dm900 enigma2[247]: 0012:  4e d7 3f 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: mask:  ff ff ff 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: mode:  00 00 00 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: ok ... now we start!!
Aug 27 22:32:04 dm900 enigma2[247]: no version filtering
Aug 27 22:32:04 dm900 enigma2[247]: 0000:  00 00 00 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: mask:  ff 00 00 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: mode:  00 00 00 00 00 00
Aug 27 22:32:04 dm900 enigma2[247]: +- 1/2 TID 4e
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBLocalTimeHandler] Receiver time is 'Sun Aug 27 22:32:05 2017'
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBLocalTimeHandler] Transponder time is 'Sun Aug 27 22:32:05 2017'
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBLocalTimeHandler] diff is 0
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBLocalTimeHandler] not changed
Aug 27 22:32:05 dm900 enigma2[247]: + 1/1 TID 00
Aug 27 22:32:05 dm900 enigma2[247]: done!
Aug 27 22:32:05 dm900 enigma2[247]: PATready
Aug 27 22:32:05 dm900 enigma2[247]: use pmtpid 0103 for service_id d73f
Aug 27 22:32:05 dm900 enigma2[247]: no version filtering
Aug 27 22:32:05 dm900 enigma2[247]: 0103:  02 d7 3f 00 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mask:  ff ff ff 00 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mode:  00 00 00 00 00 00
Aug 27 22:32:05 dm900 enigma2[247]: doing version filtering
Aug 27 22:32:05 dm900 enigma2[247]: 0000:  00 00 00 09 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mask:  ff 00 00 3f 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mode:  00 00 00 3e 00 00
Aug 27 22:32:05 dm900 enigma2[247]: sdt update done!
Aug 27 22:32:05 dm900 enigma2[247]: + 1/1 TID 02
Aug 27 22:32:05 dm900 enigma2[247]: done!
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBCAService] new service 1:0:19:D73F:227:270F:FFFF0000:0:0:0:
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBCAService] add demux 0 to slot 0 service 1:0:19:D73F:227:270F:FFFF0000:0:0:0:
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBCAService] demux 0 mask 01
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBCIInterfaces] addPMTHandler 1:0:19:D73F:227:270F:FFFF0000:0:0:0:
Aug 27 22:32:05 dm900 enigma2[247]: [eDVBCIInterfaces] gotPMT
Aug 27 22:32:05 dm900 enigma2[247]: eventNewProgramInfo 0 0
Aug 27 22:32:05 dm900 enigma2[247]: start ait on pid 0637
Aug 27 22:32:05 dm900 enigma2[247]: no version filtering
Aug 27 22:32:05 dm900 enigma2[247]: 0637:  74 00 00 00 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mask:  ff 00 00 00 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mode:  00 00 00 00 00 00
Aug 27 22:32:05 dm900 enigma2[247]: have 1 video stream(s) (0215), and 1 audio stream(s) (0216), and the pcr pid is 0215, and the text pid is 021a
Aug 27 22:32:05 dm900 enigma2[247]: decoder state: play, vpid=533, apid=534
Aug 27 22:32:05 dm900 enigma2[247]: [TeleText] serviceInfoChanged
Aug 27 22:32:05 dm900 enigma2[247]: [TeleText] TXT PID 538 DEMUX 0
Aug 27 22:32:05 dm900 enigma2[247]: [TeleText] send array('B', [1, 2, 26, 0])
Aug 27 22:32:05 dm900 enigma2[247]: doing version filtering
Aug 27 22:32:05 dm900 enigma2[247]: 0103:  02 d7 3f 09 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mask:  ff ff ff 3f 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mode:  00 00 00 3e 00 00
Aug 27 22:32:05 dm900 enigma2[247]: ++ 2/2 TID 4e
Aug 27 22:32:05 dm900 enigma2[247]: done!
Aug 27 22:32:05 dm900 enigma2[247]: doing version filtering
Aug 27 22:32:05 dm900 enigma2[247]: 0012:  4e d7 3f 27 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mask:  ff ff ff 3f 00 00
Aug 27 22:32:05 dm900 enigma2[247]: mode:  00 00 00 3e 00 00
Aug 27 22:32:06 dm900 enigma2[247]: [EPGC] start caching events(1503865926)
Aug 27 22:32:06 dm900 enigma2[247]: + 1/1 TID 74
Aug 27 22:32:06 dm900 enigma2[247]: done!
Aug 27 22:32:06 dm900 enigma2[247]: [eHbbtv] aitChanged pid=0x637
Aug 27 22:32:06 dm900 enigma2[247]: [eOipfApplication] Profile 0000, Version 1.1.1
Aug 27 22:32:06 dm900 enigma2[247]: [eOipfApplication] deu:Sat_1
Aug 27 22:32:06 dm900 enigma2[247]: [eHbbtv] New Application: Sat_1 (orgid=96, appid=1, urlBase=http://hbbtv.redbutton.de/service/, initalPath=redbutton.php?brand=s1de, controlCode=1, visibility=2)
Aug 27 22:32:06 dm900 enigma2[247]: [eHbbtv] Resolving Application Locator: dvb://current.ait/60.1
Aug 27 22:32:06 dm900 enigma2[247]: [eHbbtv] We have 1 app(s) for service 9999.551.55103
Aug 27 22:32:06 dm900 enigma2[247]: [eHbbtv] 'dvb://current.ait/60.1' resolved to 'http://hbbtv.redbutton.de/service/redbutton.php?brand=s1de'
Aug 27 22:32:06 dm900 enigma2[247]: [HbbTV].readButtonApplicationReady, appid=60.1
Aug 27 22:32:06 dm900 enigma2[247]: doing version filtering
Aug 27 22:32:06 dm900 enigma2[247]: 0637:  74 00 00 11 00 00
Aug 27 22:32:06 dm900 enigma2[247]: mask:  ff 00 00 3f 00 00
Aug 27 22:32:06 dm900 enigma2[247]: mode:  00 00 00 3e 00 00
Aug 27 22:32:09 dm900 enigma2[247]: Unhandled Error
Aug 27 22:32:09 dm900 enigma2[247]: Traceback (most recent call last):
Aug 27 22:32:09 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/twisted/python/log.py", line 73, in callWithContext
Aug 27 22:32:09 dm900 enigma2[247]:     return context.call({ILogContext: newCtx}, func, *args, **kw)
Aug 27 22:32:09 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/twisted/python/context.py", line 118, in callWithContext
Aug 27 22:32:09 dm900 enigma2[247]:     return self.currentContext().callWithContext(ctx, func, *args, **kw)
Aug 27 22:32:09 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/twisted/python/context.py", line 81, in callWithContext
Aug 27 22:32:09 dm900 enigma2[247]:     return func(*args,**kw)
Aug 27 22:32:09 dm900 enigma2[247]:   File "/usr/lib/enigma2/python/e2reactor.py", line 55, in _read
Aug 27 22:32:09 dm900 enigma2[247]:     why = w.doRead()
Aug 27 22:32:09 dm900 enigma2[247]: --- <exception caught here> ---
Aug 27 22:32:09 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/twisted/internet/udp.py", line 248, in doRead
Aug 27 22:32:09 dm900 enigma2[247]:     self.protocol.datagramReceived(data, addr)
Aug 27 22:32:09 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/coherence/upnp/core/ssdp.py", line 99, in datagramReceived
Aug 27 22:32:09 dm900 enigma2[247]:     self.notifyReceived(headers, (host, port))
Aug 27 22:32:09 dm900 enigma2[247]:   File "/usr/lib/python2.7/site-packages/coherence/upnp/core/ssdp.py", line 161, in notifyReceived
Aug 27 22:32:09 dm900 enigma2[247]:     self.info('Notification from (%s,%d) for %s' % (host, port, headers['nt']))
Aug 27 22:32:09 dm900 enigma2[247]: exceptions.KeyError: 'nt'
Aug 27 22:32:13 dm900 enigma2[247]: [EPGC] abort non avail schedule other reading
Aug 27 22:32:13 dm900 enigma2[247]: [EPGC] abort non avail viasat reading