Apr 13 23:57:06 dm900 enigma2[234]: [eDVBCIInterfaces] addPMTHandler 1:0:19:2779:409:1:C00000:0:0:0:
Apr 13 23:57:06 dm900 enigma2[234]: [eDVBCIInterfaces] gotPMT
Apr 13 23:57:06 dm900 enigma2[234]: eventNewProgramInfo 0 0
Apr 13 23:57:06 dm900 enigma2[234]: start ait on pid 0605
Apr 13 23:57:06 dm900 enigma2[234]: no version filtering
Apr 13 23:57:06 dm900 enigma2[234]: 0605:  74 00 00 00 00 00
Apr 13 23:57:06 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:57:06 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:57:06 dm900 enigma2[234]: have 1 video stream(s) (05ff), and 1 audio stream(s) (0603), and the pcr pid is 05ff, and the text pid is 0025
Apr 13 23:57:06 dm900 enigma2[234]: decoder state: play, vpid=1535, apid=1539
Apr 13 23:57:06 dm900 enigma2[234]: [TeleText] serviceInfoChanged
Apr 13 23:57:06 dm900 enigma2[234]: [TeleText] TXT PID 37 DEMUX 0
Apr 13 23:57:06 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 37, 0])
Apr 13 23:57:06 dm900 enigma2[234]: doing version filtering
Apr 13 23:57:06 dm900 enigma2[234]: 0065:  02 27 79 03 00 00
Apr 13 23:57:06 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:57:06 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:57:07 dm900 enigma2[234]: + 1/1 TID 74
Apr 13 23:57:07 dm900 enigma2[234]: done!
Apr 13 23:57:07 dm900 enigma2[234]: [eHbbtv] aitChanged pid=0x605
Apr 13 23:57:07 dm900 enigma2[234]: [eOipfApplication] Profile 0000, Version 1.1.1
Apr 13 23:57:07 dm900 enigma2[234]: [eOipfApplication] deu:QVC Beauty
Apr 13 23:57:07 dm900 enigma2[234]: [eHbbtv] New Application: QVC Beauty (orgid=33, appid=15, urlBase=https://hbbtv.qvc.de/hbbtv/qvc_beauty/, initalPath=index.php, controlCode=1, visibility=2)
Apr 13 23:57:07 dm900 enigma2[234]: [eHbbtv] Resolving Application Locator: dvb://current.ait/21.f
Apr 13 23:57:07 dm900 enigma2[234]: [eHbbtv] We have 1 app(s) for service 1.1033.10105
Apr 13 23:57:07 dm900 enigma2[234]: [eHbbtv] 'dvb://current.ait/21.f' resolved to 'https://hbbtv.qvc.de/hbbtv/qvc_beauty/index.php'
Apr 13 23:57:07 dm900 enigma2[234]: [HbbTV].readButtonApplicationReady, appid=21.f
Apr 13 23:57:07 dm900 enigma2[234]: doing version filtering
Apr 13 23:57:07 dm900 enigma2[234]: 0605:  74 00 00 01 00 00
Apr 13 23:57:07 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:57:07 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:57:07 dm900 enigma2[234]: [SEC-0] set dynamic current limiting
Apr 13 23:57:07 dm900 kernel: audio ch0: status ready isr
Apr 13 23:57:07 dm900 kernel[206]: [18976.113448] audio ch0: status ready isr
Apr 13 23:57:07 dm900 enigma2[234]: +- 1/2 TID 4e
Apr 13 23:57:07 dm900 enigma2[234]: sdt update done!
Apr 13 23:57:07 dm900 kernel: VIDEO0: first pts 0x176439704
Apr 13 23:57:07 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:57:07 dm900 kernel[206]: [18976.793800] VIDEO0: first pts 0x176439704
Apr 13 23:57:07 dm900 enigma2[234]: unhandled DVBAPI Video Event 32
Apr 13 23:57:08 dm900 kernel: VIDEO0: enable tsm
Apr 13 23:57:08 dm900 kernel[206]: [18976.943092] VIDEO0: enable tsm
Apr 13 23:57:08 dm900 enigma2[234]: ++ 2/2 TID 4e
Apr 13 23:57:08 dm900 enigma2[234]: done!
Apr 13 23:57:08 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:57:08 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656628.44
Apr 13 23:57:08 dm900 enigma2[234]: doing version filtering
Apr 13 23:57:08 dm900 enigma2[234]: 0012:  4e 27 79 11 00 00
Apr 13 23:57:08 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:57:08 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:57:08 dm900 enigma2[234]: [EPGC] start caching events(1523656628)
Apr 13 23:56:59 dm900 systemd[1]: Time has been changed
Apr 13 23:57:09 dm900 enigma2[234]: [eDVBLocalTimeHandler] Receiver time is 'Fri Apr 13 23:57:09 2018'
Apr 13 23:57:09 dm900 enigma2[234]: [eDVBLocalTimeHandler] Transponder time is 'Fri Apr 13 23:56:59 2018'
Apr 13 23:57:09 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff is -10
Apr 13 23:57:09 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Apr 13 23:57:09 dm900 enigma2[234]: [eDVBLocalTimeHandler] update RTC
Apr 13 23:57:09 dm900 enigma2[234]: [eDVBLocalTimeHandler] time_difference is -10
Apr 13 23:57:09 dm900 enigma2[234]: [eDVBLocalTimeHandler] update Linux Time
Apr 13 23:57:09 dm900 systemd[1]: Time has been changed
Apr 13 23:57:10 dm900 enigma2[234]: [InfoBar] quickSelectGlobal key:  left
Apr 13 23:57:10 dm900 enigma2[234]: [InfoBar] exec from Screens.InfoBar import InfoBar
Apr 13 23:57:10 dm900 enigma2[234]: if InfoBar and InfoBar.instance:
Apr 13 23:57:10 dm900 enigma2[234]:         InfoBar.zapUp(InfoBar.instance)
Apr 13 23:57:10 dm900 enigma2[234]: playing 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:2779:409:1:C00000:0:0:0:
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] free service 1:0:19:2779:409:1:C00000:0:0:0:
Apr 13 23:57:10 dm900 enigma2[234]: [eDBusInterface::Private::sendEvent 0
Apr 13 23:57:10 dm900 enigma2[234]: [TeleText] service stopped
Apr 13 23:57:10 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 0, 0])
Apr 13 23:57:10 dm900 enigma2[234]: disable dvb subtitles
Apr 13 23:57:10 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:57:10 dm900 enigma2[234]: decoder state: play, vpid=-1, apid=-1
Apr 13 23:57:10 dm900 kernel: stop PCR0 on dmx 0 pid:5ff
Apr 13 23:57:10 dm900 kernel: stop VIDEO0 on dmx 0 pid:5ff
Apr 13 23:57:10 dm900 kernel[206]: [18979.075833] stop PCR0 on dmx 0 pid:5ff
Apr 13 23:57:10 dm900 enigma2[234]: DEMUX_STOP - pcr - ok
Apr 13 23:57:10 dm900 enigma2[234]: DEMUX_STOP - video - ok
Apr 13 23:57:10 dm900 kernel: VIDEO0: stop decode
Apr 13 23:57:10 dm900 kernel: audio ch0: decoder stop
Apr 13 23:57:10 dm900 kernel[206]: [18979.075996] stop VIDEO0 on dmx 0 pid:5ff
Apr 13 23:57:10 dm900 enigma2[234]: VIDEO_STOP - ok
Apr 13 23:57:10 dm900 kernel[206]: [18979.076780] VIDEO0: stop decode
Apr 13 23:57:10 dm900 kernel: stop AUDIO0 on dmx 0 pid:603
Apr 13 23:57:10 dm900 kernel: audio ch0: xpt stop
Apr 13 23:57:10 dm900 kernel: sync0 1fff no decoder left in sync handling so disable
Apr 13 23:57:10 dm900 kernel: sync0: was last pcroffset0 user so free it
Apr 13 23:57:10 dm900 enigma2[234]: AUDIO_STOP - ok
Apr 13 23:57:10 dm900 enigma2[234]: DEMUX_STOP - audio - ok
Apr 13 23:57:10 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:57:10 dm900 enigma2[234]: DEMUX_STOP - ttx - ok
Apr 13 23:57:10 dm900 enigma2[234]: start release channel timer
Apr 13 23:57:10 dm900 kernel[206]: [18979.077225] audio ch0: decoder stop
Apr 13 23:57:10 dm900 kernel[206]: [18979.078489] stop AUDIO0 on dmx 0 pid:603
Apr 13 23:57:10 dm900 kernel[206]: [18979.078496] audio ch0: xpt stop
Apr 13 23:57:10 dm900 kernel[206]: [18979.078502] sync0 1fff no decoder left in sync handling so disable
Apr 13 23:57:10 dm900 kernel[206]: [18979.078506] sync0: was last pcroffset0 user so free it
Apr 13 23:57:10 dm900 enigma2[234]: [TeleText] service started
Apr 13 23:57:10 dm900 enigma2[234]: not pauseable.
Apr 13 23:57:10 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:57:10 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656630.22
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] remove channel 0x1b5ba38
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBLocalTimeHandler] remove channel 0x1b5ba38
Apr 13 23:57:10 dm900 enigma2[234]: [EPGC] remove channel 0x1b5ba38
Apr 13 23:57:10 dm900 enigma2[234]: [EPGC] abort caching events !!
Apr 13 23:57:10 dm900 enigma2[234]: [    0] preClose
Apr 13 23:57:10 dm900 enigma2[234]: allocate channel.. 041f:0001:00c00000
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] new channel 0x1b5ba38!
Apr 13 23:57:10 dm900 enigma2[234]: [    0] tune type 1
Apr 13 23:57:10 dm900 enigma2[234]: RotorCmd ffffffff, lastRotorCmd ffffffff
Apr 13 23:57:10 dm900 enigma2[234]: [    0] prepare_sat System DVB-S2 Freq 10802750H SR 22000000 INV Auto FEC 3/4 orbpos 192 modulation 8PSK pilot Auto, rolloff 0.35
Apr 13 23:57:10 dm900 enigma2[234]: [    0] tuning to 1052 mhz
Apr 13 23:57:10 dm900 enigma2[234]: OURSTATE: tuning
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] update oparm
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] lock 0
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] locked 0
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] set static current limiting
Apr 13 23:57:10 dm900 enigma2[234]: allocate Channel: res 0
Apr 13 23:57:10 dm900 enigma2[234]: allocate demux
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] invalidate current switch params
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] update current switch params
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] startTuneTimeout 5000
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] setFrontend 1
Apr 13 23:57:10 dm900 enigma2[234]: [    0] setting frontend
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] unlock 0
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] sleep 500ms
Apr 13 23:57:10 dm900 enigma2[234]: setInputSource input0 to A1
Apr 13 23:57:10 dm900 enigma2[234]: have 1 video stream(s) (04ff), and 1 audio stream(s) (0503), and the pcr pid is 04ff, and the text pid is 0024
Apr 13 23:57:10 dm900 enigma2[234]: allocate demux
Apr 13 23:57:10 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:57:10 dm900 enigma2[234]: decoder state: play, vpid=1279, apid=1283
Apr 13 23:57:10 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x4ff) - pcr - ok
Apr 13 23:57:10 dm900 enigma2[234]: DEMUX_START - pcr - ok
Apr 13 23:57:10 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x503) - audio - ok
Apr 13 23:57:10 dm900 enigma2[234]: DEMUX_START - audio - ok
Apr 13 23:57:10 dm900 enigma2[234]: AUDIO_SET_BYPASS(0) - ok
Apr 13 23:57:10 dm900 enigma2[234]: AUDIO_PAUSE - ok
Apr 13 23:57:10 dm900 kernel: start PCR0 on dmx 0 pid:4ff index 3
Apr 13 23:57:10 dm900 kernel: start AUDIO0 on dmx 0 pid:503 index 1
Apr 13 23:57:10 dm900 kernel: audio ch0: xpt start
Apr 13 23:57:10 dm900 kernel: sync0 is off so we enable it
Apr 13 23:57:10 dm900 kernel: sync0: allocated pcroffset 0
Apr 13 23:57:10 dm900 kernel: sync0: demux-index 0 pcr pid 4ff
Apr 13 23:57:10 dm900 kernel: sync0: using 3 as pcr pidchannel
Apr 13 23:57:10 dm900 kernel: audio ch0: AC3
Apr 13 23:57:10 dm900 kernel: audio ch0: decoder start
Apr 13 23:57:10 dm900 kernel: audio ch0 delay 18000 (200 ms)
Apr 13 23:57:10 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:57:10 dm900 kernel: start VIDEO0 on dmx 0 pid:4ff index 3
Apr 13 23:57:10 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:57:10 dm900 kernel[206]: [18979.140393] start PCR0 on dmx 0 pid:4ff index 3
Apr 13 23:57:10 dm900 enigma2[234]: AUDIO_PLAY - ok
Apr 13 23:57:10 dm900 enigma2[234]: Video Device: /dev/dvb/adapter0/video0
Apr 13 23:57:10 dm900 enigma2[234]: demux device: /dev/dvb/adapter0/demux0
Apr 13 23:57:10 dm900 enigma2[234]: VIDEO_SET_STREAMTYPE 1 - ok
Apr 13 23:57:10 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x4ff) - video - ok
Apr 13 23:57:10 dm900 enigma2[234]: DEMUX_START - video - ok
Apr 13 23:57:10 dm900 enigma2[234]: VIDEO_FREEZE - ok
Apr 13 23:57:10 dm900 kernel: VIDEO0: start decode
Apr 13 23:57:10 dm900 kernel[206]: [18979.140513] start AUDIO0 on dmx 0 pid:503 index 1
Apr 13 23:57:10 dm900 enigma2[234]: VIDEO_PLAY - ok
Apr 13 23:57:10 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x24) - ttx - ok
Apr 13 23:57:10 dm900 enigma2[234]: DEMUX_START - ttx - ok
Apr 13 23:57:10 dm900 enigma2[234]: VIDEO_SLOWMOTION(0) - ok
Apr 13 23:57:10 dm900 enigma2[234]: VIDEO_FAST_FORWARD(0) - ok
Apr 13 23:57:10 dm900 enigma2[234]: VIDEO_CONTINUE - ok
Apr 13 23:57:10 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:57:10 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:57:10 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656630.28
Apr 13 23:57:10 dm900 enigma2[234]: not pauseable.
Apr 13 23:57:10 dm900 enigma2[234]: [HbbTV].aitInvalidated
Apr 13 23:57:10 dm900 kernel[206]: [18979.140518] audio ch0: xpt start
Apr 13 23:57:10 dm900 kernel[206]: [18979.140538] sync0 is off so we enable it
Apr 13 23:57:10 dm900 kernel[206]: [18979.140543] sync0: allocated pcroffset 0
Apr 13 23:57:10 dm900 kernel[206]: [18979.140554] sync0: demux-index 0 pcr pid 4ff
Apr 13 23:57:10 dm900 kernel[206]: [18979.140563] sync0: using 3 as pcr pidchannel
Apr 13 23:57:10 dm900 kernel[206]: [18979.140579] audio ch0: AC3
Apr 13 23:57:10 dm900 kernel[206]: [18979.140632] audio ch0: decoder start
Apr 13 23:57:10 dm900 kernel[206]: [18979.140639] audio ch0 delay 18000 (200 ms)
Apr 13 23:57:10 dm900 kernel[206]: [18979.140643] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:57:10 dm900 kernel[206]: [18979.141299] start VIDEO0 on dmx 0 pid:4ff index 3
Apr 13 23:57:10 dm900 kernel[206]: [18979.141338] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:57:10 dm900 kernel[206]: [18979.146402] VIDEO0: start decode
Apr 13 23:57:10 dm900 enigma2[234]: [EPGC] cleanup invalid data
Apr 13 23:57:10 dm900 enigma2[234]: [    0] fe event: status 0, freq 1052750, inversion off, m_tuning 1 F
Apr 13 23:57:10 dm900 enigma2[234]: [    0] fe event: status 1f, freq 1052750, inversion off, m_tuning 1 N
Apr 13 23:57:10 dm900 enigma2[234]: OURSTATE: ok
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] channel 0x1b5ba38 running
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBLocalTimeHandler] channel 0x1b5ba38 running
Apr 13 23:57:10 dm900 enigma2[234]: no version filtering
Apr 13 23:57:10 dm900 enigma2[234]: 0014:  70 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mask:  fc 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: [EPGC] channel 0x1b5ba38 running
Apr 13 23:57:10 dm900 enigma2[234]: stop release channel timer
Apr 13 23:57:10 dm900 enigma2[234]: no version filtering
Apr 13 23:57:10 dm900 enigma2[234]: 0012:  4e 15 80 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: ok ... now we start!!
Apr 13 23:57:10 dm900 enigma2[234]: no version filtering
Apr 13 23:57:10 dm900 enigma2[234]: 0000:  00 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: [EPGC] next update in 2 sec
Apr 13 23:57:10 dm900 kernel: audio ch0: lock
Apr 13 23:57:10 dm900 kernel[206]: [18979.249850] audio ch0: lock
Apr 13 23:57:10 dm900 enigma2[234]: + 1/1 TID 00
Apr 13 23:57:10 dm900 enigma2[234]: done!
Apr 13 23:57:10 dm900 enigma2[234]: PATready
Apr 13 23:57:10 dm900 enigma2[234]: use pmtpid 0064 for service_id 1580
Apr 13 23:57:10 dm900 enigma2[234]: no version filtering
Apr 13 23:57:10 dm900 enigma2[234]: 0064:  02 15 80 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: doing version filtering
Apr 13 23:57:10 dm900 enigma2[234]: 0000:  00 00 00 1d 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:57:10 dm900 enigma2[234]: + 1/1 TID 02
Apr 13 23:57:10 dm900 enigma2[234]: done!
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] new service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] add demux 0 to slot 0 service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAService] demux 0 mask 01
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCAServiceConn] send 57 bytes to '/tmp/camd.socket'
Apr 13 23:57:10 dm900 enigma2[234]: 9f 80 32 35 03 15 80 03 00 1b 01 81 08 00 c0 00 00 04 1f 00 01 82 02 01 00 84 02 00 64 85 03 00 41 31 86 01 00 06 00 24 00 00 1b 04 ff 00 00 06 05 03 00 00 05 05 05 00 00
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCIInterfaces] addPMTHandler 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:57:10 dm900 enigma2[234]: [eDVBCIInterfaces] gotPMT
Apr 13 23:57:10 dm900 enigma2[234]: eventNewProgramInfo 0 0
Apr 13 23:57:10 dm900 enigma2[234]: start ait on pid 0505
Apr 13 23:57:10 dm900 enigma2[234]: no version filtering
Apr 13 23:57:10 dm900 enigma2[234]: 0505:  74 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:57:10 dm900 enigma2[234]: have 1 video stream(s) (04ff), and 1 audio stream(s) (0503), and the pcr pid is 04ff, and the text pid is 0024
Apr 13 23:57:10 dm900 enigma2[234]: decoder state: play, vpid=1279, apid=1283
Apr 13 23:57:10 dm900 enigma2[234]: [TeleText] serviceInfoChanged
Apr 13 23:57:10 dm900 enigma2[234]: [TeleText] TXT PID 36 DEMUX 0
Apr 13 23:57:10 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 36, 0])
Apr 13 23:57:10 dm900 enigma2[234]: doing version filtering
Apr 13 23:57:10 dm900 enigma2[234]: 0064:  02 15 80 03 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:57:10 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:57:10 dm900 enigma2[234]: illegal data unit length 0
Apr 13 23:57:10 dm900 kernel: audio ch0: first pts 0x17b7d0d38, stc 0x17b7cfacc, pcr valid
Apr 13 23:57:10 dm900 kernel[206]: [18979.570416] audio ch0: first pts 0x17b7d0d38, stc 0x17b7cfacc, pcr valid
Apr 13 23:57:10 dm900 enigma2[234]: [SEC-0] set dynamic current limiting
Apr 13 23:57:11 dm900 enigma2[234]: -+ 1/2 TID 4e
Apr 13 23:57:11 dm900 kernel: VIDEO0: first pts 0x17b7e568e
Apr 13 23:57:11 dm900 kernel: VIDEO0: resolution changed (1440 1080)
Apr 13 23:57:11 dm900 kernel[206]: [18980.073818] VIDEO0: first pts 0x17b7e568e
Apr 13 23:57:11 dm900 kernel[206]: [18980.073851] VIDEO0: resolution changed (1440 1080)
Apr 13 23:57:11 dm900 enigma2[234]: + 1/1 TID 74
Apr 13 23:57:11 dm900 enigma2[234]: done!
Apr 13 23:57:11 dm900 enigma2[234]: [eHbbtv] aitChanged pid=0x505
Apr 13 23:57:11 dm900 enigma2[234]: [eOipfApplication] Profile 0000, Version 1.1.1
Apr 13 23:57:11 dm900 enigma2[234]: [eOipfApplication] deu:QVC Plus
Apr 13 23:57:11 dm900 enigma2[234]: [eHbbtv] New Application: QVC Plus (orgid=33, appid=14, urlBase=https://hbbtv.qvc.de/hbbtv/qvc_plus/, initalPath=index.php, controlCode=1, visibility=2)
Apr 13 23:57:11 dm900 enigma2[234]: [eHbbtv] Resolving Application Locator: dvb://current.ait/21.e
Apr 13 23:57:11 dm900 enigma2[234]: [eHbbtv] We have 1 app(s) for service 1.1055.5504
Apr 13 23:57:11 dm900 enigma2[234]: [eHbbtv] 'dvb://current.ait/21.e' resolved to 'https://hbbtv.qvc.de/hbbtv/qvc_plus/index.php'
Apr 13 23:57:11 dm900 enigma2[234]: [HbbTV].readButtonApplicationReady, appid=21.e
Apr 13 23:57:11 dm900 enigma2[234]: doing version filtering
Apr 13 23:57:11 dm900 enigma2[234]: 0505:  74 00 00 01 00 00
Apr 13 23:57:11 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:57:11 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:57:11 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:57:11 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:57:11 dm900 enigma2[234]: unhandled DVBAPI Video Event 32
Apr 13 23:57:11 dm900 kernel: VIDEO0: enable tsm
Apr 13 23:57:11 dm900 kernel[206]: [18980.225097] VIDEO0: enable tsm
Apr 13 23:57:12 dm900 enigma2[234]: sdt update done!
Apr 13 23:57:12 dm900 enigma2[234]: ++ 2/2 TID 4e
Apr 13 23:57:12 dm900 enigma2[234]: done!
Apr 13 23:57:12 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:57:12 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656632.14
Apr 13 23:57:12 dm900 enigma2[234]: doing version filtering
Apr 13 23:57:12 dm900 enigma2[234]: 0012:  4e 15 80 2b 00 00
Apr 13 23:57:12 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:57:12 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:57:12 dm900 enigma2[234]: [EPGC] start caching events(1523656632)
Apr 13 23:57:12 dm900 enigma2[234]: [eDVBLocalTimeHandler] Receiver time is 'Fri Apr 13 23:57:12 2018'
Apr 13 23:57:12 dm900 enigma2[234]: [eDVBLocalTimeHandler] Transponder time is 'Fri Apr 13 23:57:02 2018'
Apr 13 23:57:12 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff is -10
Apr 13 23:57:12 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Apr 13 23:57:12 dm900 enigma2[234]: [eDVBLocalTimeHandler] update RTC
Apr 13 23:57:12 dm900 enigma2[234]: [eDVBLocalTimeHandler] time_difference is -10
Apr 13 23:57:12 dm900 enigma2[234]: [eDVBLocalTimeHandler] update Linux Time
Apr 13 23:57:02 dm900 systemd[1]: Time has been changed
Apr 13 23:57:12 dm900 systemd[1]: Time has been changed
Apr 13 23:57:13 dm900 enigma2[234]: nr_read 129492 nr_write 165531
Apr 13 23:57:13 dm900 enigma2[234]: sum 295023 prev_sum 295023
Apr 13 23:57:13 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:57:13 dm900 enigma2[234]: [IDLE] 29.9842760563 300 False
Apr 13 23:57:13 dm900 enigma2[234]: nr_read 249 nr_write 1
Apr 13 23:57:13 dm900 enigma2[234]: sum 250 prev_sum 250
Apr 13 23:57:13 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:57:13 dm900 enigma2[234]: [IDLE] 18929.611109 300 True
Apr 13 23:57:19 dm900 enigma2[234]: [EPGC] abort non avail schedule other reading
Apr 13 23:57:19 dm900 enigma2[234]: [EPGC] abort non avail viasat reading
Apr 13 23:57:19 dm900 enigma2[234]: [EPGC] nownext finished(1523656639)
Apr 13 23:57:25 dm900 enigma2[234]: It's now  Fr 13 Apr 2018 23:57:25 CEST
Apr 13 23:57:25 dm900 enigma2[234]: next real activation is Sa 14 Apr 2018 19:29:40 CEST
Apr 13 23:57:25 dm900 enigma2[234]: [timer.py] next activation: 1523656745 (in 99021 ms)
Apr 13 23:57:29 dm900 enigma2[234]: [EPGC] schedule finished(1523656649)
Apr 13 23:57:29 dm900 enigma2[234]: [EPGC] stop caching events(1523656649)
Apr 13 23:57:29 dm900 enigma2[234]: [EPGC] next update in 60 min
Apr 13 23:57:29 dm900 enigma2[234]: [EPGC] cleanup invalid data
Apr 13 23:57:30 dm900 enigma2[234]: no ancillary data in audio stream... abort radiotext pes parser
Apr 13 23:57:41 dm900 enigma2[234]: [EPGC] cleanupOutdated
Apr 13 23:57:43 dm900 enigma2[234]: nr_read 129492 nr_write 165531
Apr 13 23:57:43 dm900 enigma2[234]: sum 295023 prev_sum 295023
Apr 13 23:57:43 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:57:43 dm900 enigma2[234]: [IDLE] 59.9833490849 300 False
Apr 13 23:57:43 dm900 enigma2[234]: nr_read 249 nr_write 1
Apr 13 23:57:43 dm900 enigma2[234]: sum 250 prev_sum 250
Apr 13 23:57:43 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:57:43 dm900 enigma2[234]: [IDLE] 18959.61111 300 True
Apr 13 23:57:50 dm900 systemd[1]: Time has been changed
Apr 13 23:58:04 dm900 enigma2[234]: It's now  Fr 13 Apr 2018 23:58:04 CEST
Apr 13 23:58:04 dm900 enigma2[234]: [timer.py] next activation: 1523656784 (in 99014 ms)
Apr 13 23:58:13 dm900 enigma2[234]: nr_read 129492 nr_write 165531
Apr 13 23:58:13 dm900 enigma2[234]: sum 295023 prev_sum 295023
Apr 13 23:58:13 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:58:13 dm900 enigma2[234]: [IDLE] 89.983394146 300 False
Apr 13 23:58:13 dm900 enigma2[234]: nr_read 249 nr_write 1
Apr 13 23:58:13 dm900 enigma2[234]: sum 250 prev_sum 250
Apr 13 23:58:13 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:58:13 dm900 enigma2[234]: [IDLE] 18989.6112339 300 True
Apr 13 23:58:29 dm900 enigma2[234]: [InfoBar] quickSelectGlobal key:  ok
Apr 13 23:58:29 dm900 enigma2[234]: [InfoBar] exec InfoBar.toggleShow(InfoBar.instance)
Apr 13 23:58:31 dm900 enigma2[234]: [InfoBar] quickSelectGlobal key:  left
Apr 13 23:58:31 dm900 enigma2[234]: [InfoBar] exec from Screens.InfoBar import InfoBar
Apr 13 23:58:31 dm900 enigma2[234]: if InfoBar and InfoBar.instance:
Apr 13 23:58:31 dm900 enigma2[234]:         InfoBar.zapUp(InfoBar.instance)
Apr 13 23:58:31 dm900 enigma2[234]: playing 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] free service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:31 dm900 enigma2[234]: [eDBusInterface::Private::sendEvent 0
Apr 13 23:58:31 dm900 enigma2[234]: [TeleText] service stopped
Apr 13 23:58:31 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 0, 0])
Apr 13 23:58:31 dm900 enigma2[234]: disable dvb subtitles
Apr 13 23:58:31 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:58:31 dm900 enigma2[234]: decoder state: play, vpid=-1, apid=-1
Apr 13 23:58:31 dm900 enigma2[234]: DEMUX_STOP - pcr - ok
Apr 13 23:58:31 dm900 kernel: stop PCR0 on dmx 0 pid:4ff
Apr 13 23:58:31 dm900 kernel: stop VIDEO0 on dmx 0 pid:4ff
Apr 13 23:58:31 dm900 kernel: VIDEO0: stop decode
Apr 13 23:58:31 dm900 kernel[206]: [19060.508277] stop PCR0 on dmx 0 pid:4ff
Apr 13 23:58:31 dm900 kernel: audio ch0: decoder stop
Apr 13 23:58:31 dm900 enigma2[234]: DEMUX_STOP - video - ok
Apr 13 23:58:31 dm900 enigma2[234]: VIDEO_STOP - ok
Apr 13 23:58:31 dm900 kernel[206]: [19060.508464] stop VIDEO0 on dmx 0 pid:4ff
Apr 13 23:58:31 dm900 kernel[206]: [19060.509369] VIDEO0: stop decode
Apr 13 23:58:31 dm900 kernel: stop AUDIO0 on dmx 0 pid:503
Apr 13 23:58:31 dm900 kernel: audio ch0: xpt stop
Apr 13 23:58:31 dm900 kernel: sync0 1fff no decoder left in sync handling so disable
Apr 13 23:58:31 dm900 kernel: sync0: was last pcroffset0 user so free it
Apr 13 23:58:31 dm900 enigma2[234]: AUDIO_STOP - ok
Apr 13 23:58:31 dm900 enigma2[234]: DEMUX_STOP - audio - ok
Apr 13 23:58:31 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:58:31 dm900 enigma2[234]: DEMUX_STOP - ttx - ok
Apr 13 23:58:31 dm900 enigma2[234]: start release channel timer
Apr 13 23:58:31 dm900 kernel[206]: [19060.509864] audio ch0: decoder stop
Apr 13 23:58:31 dm900 kernel[206]: [19060.512309] stop AUDIO0 on dmx 0 pid:503
Apr 13 23:58:31 dm900 kernel[206]: [19060.512317] audio ch0: xpt stop
Apr 13 23:58:31 dm900 kernel[206]: [19060.512324] sync0 1fff no decoder left in sync handling so disable
Apr 13 23:58:31 dm900 kernel[206]: [19060.512328] sync0: was last pcroffset0 user so free it
Apr 13 23:58:31 dm900 enigma2[234]: [TeleText] service started
Apr 13 23:58:31 dm900 enigma2[234]: not pauseable.
Apr 13 23:58:31 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:31 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656711.64
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] remove channel 0x1b5ba38
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBLocalTimeHandler] remove channel 0x1b5ba38
Apr 13 23:58:31 dm900 enigma2[234]: [EPGC] remove channel 0x1b5ba38
Apr 13 23:58:31 dm900 enigma2[234]: [    0] preClose
Apr 13 23:58:31 dm900 enigma2[234]: allocate channel.. 041d:0001:00c00000
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] new channel 0x1b5ba38!
Apr 13 23:58:31 dm900 enigma2[234]: [    0] tune type 1
Apr 13 23:58:31 dm900 enigma2[234]: RotorCmd ffffffff, lastRotorCmd ffffffff
Apr 13 23:58:31 dm900 enigma2[234]: [    0] prepare_sat System DVB-S2 Freq 10773250H SR 22000000 INV Auto FEC 3/4 orbpos 192 modulation 8PSK pilot Auto, rolloff 0.20
Apr 13 23:58:31 dm900 enigma2[234]: [    0] tuning to 1023 mhz
Apr 13 23:58:31 dm900 enigma2[234]: OURSTATE: tuning
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] update oparm
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] lock 0
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] locked 0
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] set static current limiting
Apr 13 23:58:31 dm900 enigma2[234]: allocate Channel: res 0
Apr 13 23:58:31 dm900 enigma2[234]: allocate demux
Apr 13 23:58:31 dm900 enigma2[234]: setInputSource input0 to A1
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] invalidate current switch params
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] update current switch params
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] startTuneTimeout 5000
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] setFrontend 1
Apr 13 23:58:31 dm900 enigma2[234]: [    0] setting frontend
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] unlock 0
Apr 13 23:58:31 dm900 enigma2[234]: [SEC-0] sleep 500ms
Apr 13 23:58:31 dm900 enigma2[234]: have 1 video stream(s) (03ff), and 1 audio stream(s) (0403), and the pcr pid is 03ff, and the text pid is 0023
Apr 13 23:58:31 dm900 enigma2[234]: allocate demux
Apr 13 23:58:31 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:58:31 dm900 enigma2[234]: decoder state: play, vpid=1023, apid=1027
Apr 13 23:58:31 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x3ff) - pcr - ok
Apr 13 23:58:31 dm900 enigma2[234]: DEMUX_START - pcr - ok
Apr 13 23:58:31 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x403) - audio - ok
Apr 13 23:58:31 dm900 kernel: start PCR0 on dmx 0 pid:3ff index 3
Apr 13 23:58:31 dm900 kernel: start AUDIO0 on dmx 0 pid:403 index 1
Apr 13 23:58:31 dm900 kernel: audio ch0: xpt start
Apr 13 23:58:31 dm900 kernel: sync0 is off so we enable it
Apr 13 23:58:31 dm900 kernel: sync0: allocated pcroffset 0
Apr 13 23:58:31 dm900 kernel: sync0: demux-index 0 pcr pid 3ff
Apr 13 23:58:31 dm900 kernel: sync0: using 3 as pcr pidchannel
Apr 13 23:58:31 dm900 kernel: audio ch0: AC3
Apr 13 23:58:31 dm900 kernel: audio ch0: decoder start
Apr 13 23:58:31 dm900 kernel: audio ch0 delay 18000 (200 ms)
Apr 13 23:58:31 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:31 dm900 kernel: start VIDEO0 on dmx 0 pid:3ff index 3
Apr 13 23:58:31 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:31 dm900 kernel[206]: [19060.572457] start PCR0 on dmx 0 pid:3ff index 3
Apr 13 23:58:31 dm900 enigma2[234]: DEMUX_START - audio - ok
Apr 13 23:58:31 dm900 enigma2[234]: AUDIO_SET_BYPASS(0) - ok
Apr 13 23:58:31 dm900 enigma2[234]: AUDIO_PAUSE - ok
Apr 13 23:58:31 dm900 enigma2[234]: AUDIO_PLAY - ok
Apr 13 23:58:31 dm900 enigma2[234]: Video Device: /dev/dvb/adapter0/video0
Apr 13 23:58:31 dm900 enigma2[234]: demux device: /dev/dvb/adapter0/demux0
Apr 13 23:58:31 dm900 enigma2[234]: VIDEO_SET_STREAMTYPE 1 - ok
Apr 13 23:58:31 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x3ff) - video - ok
Apr 13 23:58:31 dm900 enigma2[234]: DEMUX_START - video - ok
Apr 13 23:58:31 dm900 enigma2[234]: VIDEO_FREEZE - ok
Apr 13 23:58:31 dm900 kernel[206]: [19060.572843] start AUDIO0 on dmx 0 pid:403 index 1
Apr 13 23:58:31 dm900 kernel[206]: [19060.572851] audio ch0: xpt start
Apr 13 23:58:31 dm900 kernel[206]: [19060.572871] sync0 is off so we enable it
Apr 13 23:58:31 dm900 kernel[206]: [19060.572877] sync0: allocated pcroffset 0
Apr 13 23:58:31 dm900 kernel[206]: [19060.572888] sync0: demux-index 0 pcr pid 3ff
Apr 13 23:58:31 dm900 kernel[206]: [19060.572900] sync0: using 3 as pcr pidchannel
Apr 13 23:58:31 dm900 enigma2[234]: VIDEO_PLAY - ok
Apr 13 23:58:31 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x23) - ttx - ok
Apr 13 23:58:31 dm900 enigma2[234]: DEMUX_START - ttx - ok
Apr 13 23:58:31 dm900 enigma2[234]: VIDEO_SLOWMOTION(0) - ok
Apr 13 23:58:31 dm900 enigma2[234]: VIDEO_FAST_FORWARD(0) - ok
Apr 13 23:58:31 dm900 kernel: VIDEO0: start decode
Apr 13 23:58:31 dm900 kernel[206]: [19060.572918] audio ch0: AC3
Apr 13 23:58:31 dm900 enigma2[234]: VIDEO_CONTINUE - ok
Apr 13 23:58:31 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:58:31 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:31 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656711.7
Apr 13 23:58:31 dm900 enigma2[234]: not pauseable.
Apr 13 23:58:31 dm900 kernel[206]: [19060.573184] audio ch0: decoder start
Apr 13 23:58:31 dm900 enigma2[234]: [HbbTV].aitInvalidated
Apr 13 23:58:31 dm900 kernel[206]: [19060.573200] audio ch0 delay 18000 (200 ms)
Apr 13 23:58:31 dm900 kernel[206]: [19060.573206] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:31 dm900 kernel[206]: [19060.574097] start VIDEO0 on dmx 0 pid:3ff index 3
Apr 13 23:58:31 dm900 kernel[206]: [19060.574137] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:31 dm900 kernel[206]: [19060.579418] VIDEO0: start decode
Apr 13 23:58:31 dm900 kernel: audio ch0: first pts 0x9f045fc, stc 0x10f492922, pcr invalid
Apr 13 23:58:31 dm900 kernel[206]: [19060.639549] audio ch0: first pts 0x9f045fc, stc 0x10f492922, pcr invalid
Apr 13 23:58:31 dm900 enigma2[234]: [    0] fe event: status 0, freq 1023250, inversion off, m_tuning 1 F
Apr 13 23:58:31 dm900 enigma2[234]: [    0] fe event: status 1, freq 1023250, inversion off, m_tuning 1 N
Apr 13 23:58:31 dm900 kernel: audio ch0: lock
Apr 13 23:58:31 dm900 kernel: bcm_audio_decoder_sample_rate_change_isr: 24 callbacks suppressed
Apr 13 23:58:31 dm900 kernel: audio ch0: sample rate changed isr (48000)
Apr 13 23:58:31 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:31 dm900 kernel: audio ch0: pts error 0 PTS 0x09f045fc, STC 0x09f03e18, type 0
Apr 13 23:58:31 dm900 kernel[206]: [19060.669983] audio ch0: lock
Apr 13 23:58:31 dm900 kernel[206]: [19060.670003] bcm_audio_decoder_sample_rate_change_isr: 24 callbacks suppressed
Apr 13 23:58:31 dm900 kernel[206]: [19060.670008] audio ch0: sample rate changed isr (48000)
Apr 13 23:58:31 dm900 kernel[206]: [19060.670025] audio ch0: tsm fail
Apr 13 23:58:31 dm900 kernel[206]: [19060.670033] audio ch0: pts error 0 PTS 0x09f045fc, STC 0x09f03e18, type 0
Apr 13 23:58:31 dm900 enigma2[234]: [    0] fe event: status 1f, freq 1023250, inversion off, m_tuning 2 N
Apr 13 23:58:31 dm900 enigma2[234]: OURSTATE: ok
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] channel 0x1b5ba38 running
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBLocalTimeHandler] channel 0x1b5ba38 running
Apr 13 23:58:31 dm900 enigma2[234]: no version filtering
Apr 13 23:58:31 dm900 enigma2[234]: 0014:  70 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mask:  fc 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: [EPGC] channel 0x1b5ba38 running
Apr 13 23:58:31 dm900 enigma2[234]: stop release channel timer
Apr 13 23:58:31 dm900 enigma2[234]: no version filtering
Apr 13 23:58:31 dm900 enigma2[234]: 0012:  4e 52 6f 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: [EPGC] next update in 9 min
Apr 13 23:58:31 dm900 enigma2[234]: ok ... now we start!!
Apr 13 23:58:31 dm900 enigma2[234]: no version filtering
Apr 13 23:58:31 dm900 enigma2[234]: 0000:  00 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: + 1/1 TID 00
Apr 13 23:58:31 dm900 enigma2[234]: done!
Apr 13 23:58:31 dm900 enigma2[234]: PATready
Apr 13 23:58:31 dm900 enigma2[234]: use pmtpid 0063 for service_id 526f
Apr 13 23:58:31 dm900 enigma2[234]: no version filtering
Apr 13 23:58:31 dm900 enigma2[234]: 0063:  02 52 6f 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:31 dm900 enigma2[234]: 0000:  00 00 00 1f 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:31 dm900 enigma2[234]: + 1/1 TID 02
Apr 13 23:58:31 dm900 enigma2[234]: done!
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] new service 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] add demux 0 to slot 0 service 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAService] demux 0 mask 01
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCAServiceConn] send 57 bytes to '/tmp/camd.socket'
Apr 13 23:58:31 dm900 enigma2[234]: 9f 80 32 35 03 52 6f 05 00 1b 01 81 08 00 c0 00 00 04 1d 00 01 82 02 01 00 84 02 00 63 85 03 00 41 31 86 01 00 06 00 23 00 00 1b 03 ff 00 00 06 04 03 00 00 05 04 05 00 00
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCIInterfaces] addPMTHandler 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:31 dm900 enigma2[234]: [eDVBCIInterfaces] gotPMT
Apr 13 23:58:31 dm900 enigma2[234]: eventNewProgramInfo 0 0
Apr 13 23:58:31 dm900 enigma2[234]: start ait on pid 0405
Apr 13 23:58:31 dm900 enigma2[234]: no version filtering
Apr 13 23:58:31 dm900 enigma2[234]: 0405:  74 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:31 dm900 enigma2[234]: have 1 video stream(s) (03ff), and 1 audio stream(s) (0403), and the pcr pid is 03ff, and the text pid is 0023
Apr 13 23:58:31 dm900 enigma2[234]: decoder state: play, vpid=1023, apid=1027
Apr 13 23:58:31 dm900 enigma2[234]: [TeleText] serviceInfoChanged
Apr 13 23:58:31 dm900 enigma2[234]: [TeleText] TXT PID 35 DEMUX 0
Apr 13 23:58:31 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 35, 0])
Apr 13 23:58:31 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:31 dm900 enigma2[234]: 0063:  02 52 6f 05 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:58:31 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:32 dm900 kernel: audio ch0: status ready isr
Apr 13 23:58:32 dm900 kernel[206]: [19060.968647] audio ch0: status ready isr
Apr 13 23:58:32 dm900 enigma2[234]: [SEC-0] set dynamic current limiting
Apr 13 23:58:32 dm900 enigma2[234]: -+ 1/2 TID 4e
Apr 13 23:58:32 dm900 enigma2[234]: + 1/1 TID 74
Apr 13 23:58:32 dm900 enigma2[234]: done!
Apr 13 23:58:32 dm900 enigma2[234]: [eHbbtv] aitChanged pid=0x405
Apr 13 23:58:32 dm900 enigma2[234]: [eOipfApplication] Profile 0000, Version 1.1.1
Apr 13 23:58:32 dm900 enigma2[234]: [eOipfApplication] deu:QVC HD
Apr 13 23:58:32 dm900 enigma2[234]: [eHbbtv] New Application: QVC HD (orgid=33, appid=13, urlBase=https://hbbtv.qvc.de/hbbtv/qvc/, initalPath=index.php, controlCode=1, visibility=2)
Apr 13 23:58:32 dm900 enigma2[234]: [eHbbtv] Resolving Application Locator: dvb://current.ait/21.d
Apr 13 23:58:32 dm900 enigma2[234]: [eHbbtv] We have 1 app(s) for service 1.1053.21103
Apr 13 23:58:32 dm900 enigma2[234]: [eHbbtv] 'dvb://current.ait/21.d' resolved to 'https://hbbtv.qvc.de/hbbtv/qvc/index.php'
Apr 13 23:58:32 dm900 enigma2[234]: [HbbTV].readButtonApplicationReady, appid=21.d
Apr 13 23:58:32 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:32 dm900 enigma2[234]: 0405:  74 00 00 01 00 00
Apr 13 23:58:32 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:58:32 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:32 dm900 enigma2[234]: sdt update done!
Apr 13 23:58:33 dm900 kernel: VIDEO0: first pts 0x9f2dc10
Apr 13 23:58:33 dm900 kernel: VIDEO0: resolution changed (1920 1080)
Apr 13 23:58:33 dm900 kernel[206]: [19061.934493] VIDEO0: first pts 0x9f2dc10
Apr 13 23:58:33 dm900 kernel[206]: [19061.934522] VIDEO0: resolution changed (1920 1080)
Apr 13 23:58:33 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:33 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:33 dm900 enigma2[234]: unhandled DVBAPI Video Event 32
Apr 13 23:58:33 dm900 kernel: VIDEO0: enable tsm
Apr 13 23:58:33 dm900 kernel[206]: [19062.084081] VIDEO0: enable tsm
Apr 13 23:58:33 dm900 enigma2[234]: ++ 2/2 TID 4e
Apr 13 23:58:33 dm900 enigma2[234]: done!
Apr 13 23:58:33 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:33 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656713.37
Apr 13 23:58:33 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:33 dm900 enigma2[234]: 0012:  4e 52 6f 35 00 00
Apr 13 23:58:33 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:58:33 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:34 dm900 enigma2[234]: [eDVBLocalTimeHandler] Receiver time is 'Fri Apr 13 23:58:34 2018'
Apr 13 23:58:34 dm900 enigma2[234]: [eDVBLocalTimeHandler] Transponder time is 'Fri Apr 13 23:58:24 2018'
Apr 13 23:58:34 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff is -10
Apr 13 23:58:34 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Apr 13 23:58:24 dm900 enigma2[234]: [eDVBLocalTimeHandler] update RTC
Apr 13 23:58:24 dm900 enigma2[234]: [eDVBLocalTimeHandler] time_difference is -10
Apr 13 23:58:24 dm900 enigma2[234]: [eDVBLocalTimeHandler] update Linux Time
Apr 13 23:58:24 dm900 systemd[1]: Time has been changed
Apr 13 23:58:34 dm900 systemd[1]: Time has been changed
Apr 13 23:58:39 dm900 systemd[1]: Time has been changed
Apr 13 23:58:39 dm900 enigma2[234]: [InfoBar] quickSelectGlobal key:  right
Apr 13 23:58:39 dm900 enigma2[234]: [InfoBar] exec from Screens.InfoBar import InfoBar
Apr 13 23:58:39 dm900 enigma2[234]: if InfoBar and InfoBar.instance:
Apr 13 23:58:39 dm900 enigma2[234]:         InfoBar.zapDown(InfoBar.instance)
Apr 13 23:58:39 dm900 enigma2[234]: playing 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:39 dm900 enigma2[234]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:39 dm900 enigma2[234]: [eDVBCAService] free service 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:39 dm900 enigma2[234]: [eDBusInterface::Private::sendEvent 0
Apr 13 23:58:39 dm900 enigma2[234]: [TeleText] service stopped
Apr 13 23:58:39 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 0, 0])
Apr 13 23:58:39 dm900 enigma2[234]: disable dvb subtitles
Apr 13 23:58:39 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:58:39 dm900 enigma2[234]: decoder state: play, vpid=-1, apid=-1
Apr 13 23:58:39 dm900 enigma2[234]: DEMUX_STOP - pcr - ok
Apr 13 23:58:39 dm900 kernel: stop PCR0 on dmx 0 pid:3ff
Apr 13 23:58:39 dm900 kernel: stop VIDEO0 on dmx 0 pid:3ff
Apr 13 23:58:39 dm900 kernel: VIDEO0: stop decode
Apr 13 23:58:39 dm900 kernel[206]: [19068.797130] stop PCR0 on dmx 0 pid:3ff
Apr 13 23:58:39 dm900 enigma2[234]: DEMUX_STOP - video - ok
Apr 13 23:58:39 dm900 enigma2[234]: VIDEO_STOP - ok
Apr 13 23:58:39 dm900 kernel: audio ch0: decoder stop
Apr 13 23:58:39 dm900 kernel[206]: [19068.797326] stop VIDEO0 on dmx 0 pid:3ff
Apr 13 23:58:39 dm900 kernel[206]: [19068.798163] VIDEO0: stop decode
Apr 13 23:58:39 dm900 kernel: stop AUDIO0 on dmx 0 pid:403
Apr 13 23:58:39 dm900 kernel: audio ch0: xpt stop
Apr 13 23:58:39 dm900 kernel: sync0 1fff no decoder left in sync handling so disable
Apr 13 23:58:39 dm900 kernel: sync0: was last pcroffset0 user so free it
Apr 13 23:58:39 dm900 enigma2[234]: AUDIO_STOP - ok
Apr 13 23:58:39 dm900 enigma2[234]: DEMUX_STOP - audio - ok
Apr 13 23:58:39 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:58:39 dm900 enigma2[234]: DEMUX_STOP - ttx - ok
Apr 13 23:58:39 dm900 enigma2[234]: start release channel timer
Apr 13 23:58:39 dm900 kernel[206]: [19068.798600] audio ch0: decoder stop
Apr 13 23:58:39 dm900 kernel[206]: [19068.799845] stop AUDIO0 on dmx 0 pid:403
Apr 13 23:58:39 dm900 kernel[206]: [19068.799852] audio ch0: xpt stop
Apr 13 23:58:39 dm900 kernel[206]: [19068.799859] sync0 1fff no decoder left in sync handling so disable
Apr 13 23:58:39 dm900 kernel[206]: [19068.799863] sync0: was last pcroffset0 user so free it
Apr 13 23:58:39 dm900 enigma2[234]: [TeleText] service started
Apr 13 23:58:39 dm900 enigma2[234]: not pauseable.
Apr 13 23:58:39 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:39 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656719.92
Apr 13 23:58:39 dm900 enigma2[234]: [eDVBCAService] remove channel 0x1b5ba38
Apr 13 23:58:39 dm900 enigma2[234]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok
Apr 13 23:58:39 dm900 enigma2[234]: [eDVBLocalTimeHandler] remove channel 0x1b5ba38
Apr 13 23:58:39 dm900 enigma2[234]: [EPGC] remove channel 0x1b5ba38
Apr 13 23:58:39 dm900 enigma2[234]: [    0] preClose
Apr 13 23:58:39 dm900 enigma2[234]: allocate channel.. 041f:0001:00c00000
Apr 13 23:58:39 dm900 enigma2[234]: [eDVBCAService] new channel 0x1b5ba38!
Apr 13 23:58:39 dm900 enigma2[234]: [    0] tune type 1
Apr 13 23:58:39 dm900 enigma2[234]: RotorCmd ffffffff, lastRotorCmd ffffffff
Apr 13 23:58:39 dm900 enigma2[234]: [    0] prepare_sat System DVB-S2 Freq 10802750H SR 22000000 INV Auto FEC 3/4 orbpos 192 modulation 8PSK pilot Auto, rolloff 0.35
Apr 13 23:58:39 dm900 enigma2[234]: [    0] tuning to 1052 mhz
Apr 13 23:58:39 dm900 enigma2[234]: OURSTATE: tuning
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] update oparm
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] lock 0
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] locked 0
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] set static current limiting
Apr 13 23:58:39 dm900 enigma2[234]: allocate Channel: res 0
Apr 13 23:58:39 dm900 enigma2[234]: allocate demux
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] invalidate current switch params
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] update current switch params
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] startTuneTimeout 5000
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] setFrontend 1
Apr 13 23:58:39 dm900 enigma2[234]: [    0] setting frontend
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] unlock 0
Apr 13 23:58:39 dm900 enigma2[234]: [SEC-0] sleep 500ms
Apr 13 23:58:39 dm900 enigma2[234]: setInputSource input0 to A1
Apr 13 23:58:39 dm900 kernel: start PCR0 on dmx 0 pid:4ff index 4
Apr 13 23:58:39 dm900 kernel: start AUDIO0 on dmx 0 pid:503 index 1
Apr 13 23:58:39 dm900 kernel: audio ch0: xpt start
Apr 13 23:58:39 dm900 kernel: sync0 is off so we enable it
Apr 13 23:58:39 dm900 kernel: sync0: allocated pcroffset 0
Apr 13 23:58:39 dm900 kernel: sync0: demux-index 0 pcr pid 4ff
Apr 13 23:58:39 dm900 kernel: sync0: using 4 as pcr pidchannel
Apr 13 23:58:39 dm900 kernel: audio ch0: AC3
Apr 13 23:58:39 dm900 kernel: audio ch0: decoder start
Apr 13 23:58:39 dm900 kernel: audio ch0 delay 18000 (200 ms)
Apr 13 23:58:39 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:39 dm900 kernel: start VIDEO0 on dmx 0 pid:4ff index 4
Apr 13 23:58:39 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:39 dm900 kernel[206]: [19068.818598] start PCR0 on dmx 0 pid:4ff index 4
Apr 13 23:58:39 dm900 enigma2[234]: have 1 video stream(s) (04ff), and 1 audio stream(s) (0503), and the pcr pid is 04ff, and the text pid is 0024
Apr 13 23:58:39 dm900 enigma2[234]: allocate demux
Apr 13 23:58:39 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:58:39 dm900 enigma2[234]: decoder state: play, vpid=1279, apid=1283
Apr 13 23:58:39 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x4ff) - pcr - ok
Apr 13 23:58:39 dm900 enigma2[234]: DEMUX_START - pcr - ok
Apr 13 23:58:39 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x503) - audio - ok
Apr 13 23:58:39 dm900 enigma2[234]: DEMUX_START - audio - ok
Apr 13 23:58:39 dm900 enigma2[234]: AUDIO_SET_BYPASS(0) - ok
Apr 13 23:58:39 dm900 enigma2[234]: AUDIO_PAUSE - ok
Apr 13 23:58:39 dm900 enigma2[234]: AUDIO_PLAY - ok
Apr 13 23:58:39 dm900 enigma2[234]: Video Device: /dev/dvb/adapter0/video0
Apr 13 23:58:39 dm900 enigma2[234]: demux device: /dev/dvb/adapter0/demux0
Apr 13 23:58:39 dm900 enigma2[234]: VIDEO_SET_STREAMTYPE 1 - ok
Apr 13 23:58:39 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x4ff) - video - ok
Apr 13 23:58:39 dm900 enigma2[234]: DEMUX_START - video - ok
Apr 13 23:58:39 dm900 enigma2[234]: VIDEO_FREEZE - ok
Apr 13 23:58:39 dm900 kernel: VIDEO0: start decode
Apr 13 23:58:39 dm900 kernel[206]: [19068.819104] start AUDIO0 on dmx 0 pid:503 index 1
Apr 13 23:58:39 dm900 enigma2[234]: VIDEO_PLAY - ok
Apr 13 23:58:39 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x24) - ttx - ok
Apr 13 23:58:39 dm900 enigma2[234]: DEMUX_START - ttx - ok
Apr 13 23:58:39 dm900 enigma2[234]: VIDEO_SLOWMOTION(0) - ok
Apr 13 23:58:39 dm900 enigma2[234]: VIDEO_FAST_FORWARD(0) - ok
Apr 13 23:58:39 dm900 enigma2[234]: VIDEO_CONTINUE - ok
Apr 13 23:58:39 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:58:39 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:39 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656719.94
Apr 13 23:58:39 dm900 enigma2[234]: not pauseable.
Apr 13 23:58:39 dm900 enigma2[234]: [HbbTV].aitInvalidated
Apr 13 23:58:39 dm900 kernel[206]: [19068.819120] audio ch0: xpt start
Apr 13 23:58:39 dm900 kernel[206]: [19068.819143] sync0 is off so we enable it
Apr 13 23:58:39 dm900 kernel[206]: [19068.819151] sync0: allocated pcroffset 0
Apr 13 23:58:39 dm900 kernel[206]: [19068.819165] sync0: demux-index 0 pcr pid 4ff
Apr 13 23:58:39 dm900 kernel[206]: [19068.819177] sync0: using 4 as pcr pidchannel
Apr 13 23:58:39 dm900 kernel[206]: [19068.819199] audio ch0: AC3
Apr 13 23:58:39 dm900 kernel[206]: [19068.819467] audio ch0: decoder start
Apr 13 23:58:39 dm900 kernel[206]: [19068.819475] audio ch0 delay 18000 (200 ms)
Apr 13 23:58:39 dm900 kernel[206]: [19068.819482] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:39 dm900 kernel[206]: [19068.820618] start VIDEO0 on dmx 0 pid:4ff index 4
Apr 13 23:58:39 dm900 kernel[206]: [19068.820655] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:39 dm900 kernel[206]: [19068.825912] VIDEO0: start decode
Apr 13 23:58:39 dm900 kernel: audio ch0: lock
Apr 13 23:58:39 dm900 kernel: audio ch0: sample rate changed isr (48000)
Apr 13 23:58:39 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:39 dm900 kernel: audio ch0: ignore pts error when audio is not running
Apr 13 23:58:39 dm900 kernel[206]: [19068.853964] audio ch0: lock
Apr 13 23:58:39 dm900 kernel[206]: [19068.853987] audio ch0: sample rate changed isr (48000)
Apr 13 23:58:39 dm900 kernel[206]: [19068.854003] audio ch0: tsm fail
Apr 13 23:58:39 dm900 kernel[206]: [19068.854009] audio ch0: ignore pts error when audio is not running
Apr 13 23:58:40 dm900 enigma2[234]: [    0] fe event: status 0, freq 1052750, inversion off, m_tuning 1 F
Apr 13 23:58:40 dm900 enigma2[234]: [    0] fe event: status 1, freq 1052750, inversion off, m_tuning 1 N
Apr 13 23:58:40 dm900 kernel: audio ch0: unlock
Apr 13 23:58:40 dm900 kernel[206]: [19068.945705] audio ch0: unlock
Apr 13 23:58:40 dm900 kernel: audio ch0: lock
Apr 13 23:58:40 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:40 dm900 kernel: audio ch0: ignore pts error when audio is not running
Apr 13 23:58:40 dm900 kernel[206]: [19068.976372] audio ch0: lock
Apr 13 23:58:40 dm900 kernel[206]: [19068.976400] audio ch0: tsm fail
Apr 13 23:58:40 dm900 kernel[206]: [19068.976423] audio ch0: ignore pts error when audio is not running
Apr 13 23:58:40 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:40 dm900 kernel: audio ch0: ignore pts error when audio is not running
Apr 13 23:58:40 dm900 kernel: audio ch0: first pts 0x17bf7e0f8, stc 0x627fa56, pcr invalid
Apr 13 23:58:40 dm900 kernel[206]: [19069.010726] audio ch0: tsm fail
Apr 13 23:58:40 dm900 kernel[206]: [19069.010733] audio ch0: ignore pts error when audio is not running
Apr 13 23:58:40 dm900 kernel[206]: [19069.010746] audio ch0: first pts 0x17bf7e0f8, stc 0x627fa56, pcr invalid
Apr 13 23:58:40 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:40 dm900 kernel: audio ch0: pts error 0 PTS 0x7bf7e0f8, STC 0x7bf7d922, type 0
Apr 13 23:58:40 dm900 kernel[206]: [19069.041249] audio ch0: tsm fail
Apr 13 23:58:40 dm900 kernel[206]: [19069.041271] audio ch0: pts error 0 PTS 0x7bf7e0f8, STC 0x7bf7d922, type 0
Apr 13 23:58:40 dm900 enigma2[234]: [    0] fe event: status 1f, freq 1052750, inversion off, m_tuning 2 N
Apr 13 23:58:40 dm900 enigma2[234]: OURSTATE: ok
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBCAService] channel 0x1b5ba38 running
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBLocalTimeHandler] channel 0x1b5ba38 running
Apr 13 23:58:40 dm900 enigma2[234]: no version filtering
Apr 13 23:58:40 dm900 enigma2[234]: 0014:  70 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mask:  fc 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: [EPGC] channel 0x1b5ba38 running
Apr 13 23:58:40 dm900 enigma2[234]: [EPGC] next update in 58 min
Apr 13 23:58:40 dm900 enigma2[234]: stop release channel timer
Apr 13 23:58:40 dm900 enigma2[234]: no version filtering
Apr 13 23:58:40 dm900 enigma2[234]: 0012:  4e 15 80 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: ok ... now we start!!
Apr 13 23:58:40 dm900 enigma2[234]: no version filtering
Apr 13 23:58:40 dm900 enigma2[234]: 0000:  00 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: + 1/1 TID 00
Apr 13 23:58:40 dm900 enigma2[234]: done!
Apr 13 23:58:40 dm900 enigma2[234]: PATready
Apr 13 23:58:40 dm900 enigma2[234]: use pmtpid 0064 for service_id 1580
Apr 13 23:58:40 dm900 enigma2[234]: no version filtering
Apr 13 23:58:40 dm900 enigma2[234]: 0064:  02 15 80 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:40 dm900 enigma2[234]: 0000:  00 00 00 1d 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBLocalTimeHandler] Receiver time is 'Fri Apr 13 23:58:40 2018'
Apr 13 23:58:30 dm900 enigma2[234]: [eDVBLocalTimeHandler] Transponder time is 'Fri Apr 13 23:58:30 2018'
Apr 13 23:58:30 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff is -10
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBLocalTimeHandler] update RTC
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBLocalTimeHandler] time_difference is -10
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBLocalTimeHandler] update Linux Time
Apr 13 23:58:30 dm900 systemd[1]: Time has been changed
Apr 13 23:58:40 dm900 systemd[1]: Time has been changed
Apr 13 23:58:40 dm900 enigma2[234]: + 1/1 TID 02
Apr 13 23:58:40 dm900 enigma2[234]: done!
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBCAService] new service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBCAService] add demux 0 to slot 0 service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBCAService] demux 0 mask 01
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBCAServiceConn] send 57 bytes to '/tmp/camd.socket'
Apr 13 23:58:40 dm900 enigma2[234]: 9f 80 32 35 03 15 80 03 00 1b 01 81 08 00 c0 00 00 04 1f 00 01 82 02 01 00 84 02 00 64 85 03 00 41 31 86 01 00 06 00 24 00 00 1b 04 ff 00 00 06 05 03 00 00 05 05 05 00 00
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBCIInterfaces] addPMTHandler 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:40 dm900 enigma2[234]: [eDVBCIInterfaces] gotPMT
Apr 13 23:58:40 dm900 enigma2[234]: eventNewProgramInfo 0 0
Apr 13 23:58:40 dm900 enigma2[234]: start ait on pid 0505
Apr 13 23:58:40 dm900 enigma2[234]: no version filtering
Apr 13 23:58:40 dm900 enigma2[234]: 0505:  74 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:40 dm900 enigma2[234]: have 1 video stream(s) (04ff), and 1 audio stream(s) (0503), and the pcr pid is 04ff, and the text pid is 0024
Apr 13 23:58:40 dm900 enigma2[234]: decoder state: play, vpid=1279, apid=1283
Apr 13 23:58:40 dm900 enigma2[234]: [TeleText] serviceInfoChanged
Apr 13 23:58:40 dm900 enigma2[234]: [TeleText] TXT PID 36 DEMUX 0
Apr 13 23:58:40 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 36, 0])
Apr 13 23:58:40 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:40 dm900 enigma2[234]: 0064:  02 15 80 03 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:58:40 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:40 dm900 enigma2[234]: -+ 1/2 TID 4e
Apr 13 23:58:40 dm900 enigma2[234]: [SEC-0] set dynamic current limiting
Apr 13 23:58:40 dm900 kernel: audio ch0: status ready isr
Apr 13 23:58:40 dm900 kernel[206]: [19069.339725] audio ch0: status ready isr
Apr 13 23:58:40 dm900 kernel: VIDEO0: first pts 0x628fe4c
Apr 13 23:58:40 dm900 kernel: VIDEO0: resolution changed (1280 1080)
Apr 13 23:58:40 dm900 kernel[206]: [19069.514530] VIDEO0: first pts 0x628fe4c
Apr 13 23:58:40 dm900 kernel[206]: [19069.514564] VIDEO0: resolution changed (1280 1080)
Apr 13 23:58:40 dm900 kernel: VIDEO0: enable tsm
Apr 13 23:58:40 dm900 kernel[206]: [19069.664115] VIDEO0: enable tsm
Apr 13 23:58:40 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:40 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:40 dm900 enigma2[234]: unhandled DVBAPI Video Event 32
Apr 13 23:58:41 dm900 enigma2[234]: + 1/1 TID 74
Apr 13 23:58:41 dm900 enigma2[234]: done!
Apr 13 23:58:41 dm900 enigma2[234]: [eHbbtv] aitChanged pid=0x505
Apr 13 23:58:41 dm900 enigma2[234]: [eOipfApplication] Profile 0000, Version 1.1.1
Apr 13 23:58:41 dm900 enigma2[234]: [eOipfApplication] deu:QVC Plus
Apr 13 23:58:41 dm900 enigma2[234]: [eHbbtv] New Application: QVC Plus (orgid=33, appid=14, urlBase=https://hbbtv.qvc.de/hbbtv/qvc_plus/, initalPath=index.php, controlCode=1, visibility=2)
Apr 13 23:58:41 dm900 enigma2[234]: [eHbbtv] Resolving Application Locator: dvb://current.ait/21.e
Apr 13 23:58:41 dm900 enigma2[234]: [eHbbtv] We have 1 app(s) for service 1.1055.5504
Apr 13 23:58:41 dm900 enigma2[234]: [eHbbtv] 'dvb://current.ait/21.e' resolved to 'https://hbbtv.qvc.de/hbbtv/qvc_plus/index.php'
Apr 13 23:58:41 dm900 enigma2[234]: [HbbTV].readButtonApplicationReady, appid=21.e
Apr 13 23:58:41 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:41 dm900 enigma2[234]: 0505:  74 00 00 01 00 00
Apr 13 23:58:41 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:58:41 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:41 dm900 enigma2[234]: ++ 2/2 TID 4e
Apr 13 23:58:41 dm900 enigma2[234]: done!
Apr 13 23:58:41 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:41 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656721.41
Apr 13 23:58:41 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:41 dm900 enigma2[234]: 0012:  4e 15 80 2b 00 00
Apr 13 23:58:41 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:58:41 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:41 dm900 enigma2[234]: [EPGC] cleanupOutdated
Apr 13 23:58:41 dm900 kernel: VIDEO0: resolution changed (1440 1080)
Apr 13 23:58:41 dm900 kernel[206]: [19070.434546] VIDEO0: resolution changed (1440 1080)
Apr 13 23:58:41 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:42 dm900 enigma2[234]: sdt update done!
Apr 13 23:58:43 dm900 enigma2[234]: nr_read 129492 nr_write 165531
Apr 13 23:58:43 dm900 enigma2[234]: sum 295023 prev_sum 295023
Apr 13 23:58:43 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:58:43 dm900 enigma2[234]: [IDLE] 119.970286131 300 False
Apr 13 23:58:43 dm900 enigma2[234]: nr_read 249 nr_write 1
Apr 13 23:58:43 dm900 enigma2[234]: sum 250 prev_sum 250
Apr 13 23:58:43 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:58:43 dm900 enigma2[234]: [IDLE] 19019.5975411 300 True
Apr 13 23:58:49 dm900 enigma2[234]: [InfoBar] quickSelectGlobal key:  ok
Apr 13 23:58:49 dm900 enigma2[234]: [InfoBar] exec InfoBar.toggleShow(InfoBar.instance)
Apr 13 23:58:50 dm900 systemd[1]: Time has been changed
Apr 13 23:58:52 dm900 enigma2[234]: [InfoBar] quickSelectGlobal key:  ok
Apr 13 23:58:52 dm900 enigma2[234]: [InfoBar] exec InfoBar.toggleShow(InfoBar.instance)
Apr 13 23:58:53 dm900 enigma2[234]: [InfoBar] quickSelectGlobal key:  left
Apr 13 23:58:53 dm900 enigma2[234]: [InfoBar] exec from Screens.InfoBar import InfoBar
Apr 13 23:58:53 dm900 enigma2[234]: if InfoBar and InfoBar.instance:
Apr 13 23:58:53 dm900 enigma2[234]:         InfoBar.zapUp(InfoBar.instance)
Apr 13 23:58:53 dm900 enigma2[234]: playing 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] free service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:53 dm900 enigma2[234]: [eDBusInterface::Private::sendEvent 0
Apr 13 23:58:53 dm900 enigma2[234]: [TeleText] service stopped
Apr 13 23:58:53 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 0, 0])
Apr 13 23:58:53 dm900 enigma2[234]: disable dvb subtitles
Apr 13 23:58:53 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:58:53 dm900 enigma2[234]: decoder state: play, vpid=-1, apid=-1
Apr 13 23:58:53 dm900 kernel: stop PCR0 on dmx 0 pid:4ff
Apr 13 23:58:53 dm900 kernel: stop VIDEO0 on dmx 0 pid:4ff
Apr 13 23:58:53 dm900 kernel[206]: [19081.957886] stop PCR0 on dmx 0 pid:4ff
Apr 13 23:58:53 dm900 enigma2[234]: DEMUX_STOP - pcr - ok
Apr 13 23:58:53 dm900 kernel: VIDEO0: stop decode
Apr 13 23:58:53 dm900 kernel[206]: [19081.958058] stop VIDEO0 on dmx 0 pid:4ff
Apr 13 23:58:53 dm900 enigma2[234]: DEMUX_STOP - video - ok
Apr 13 23:58:53 dm900 enigma2[234]: VIDEO_STOP - ok
Apr 13 23:58:53 dm900 kernel: audio ch0: decoder stop
Apr 13 23:58:53 dm900 kernel[206]: [19081.958886] VIDEO0: stop decode
Apr 13 23:58:53 dm900 kernel[206]: [19081.959422] audio ch0: decoder stop
Apr 13 23:58:53 dm900 enigma2[234]: AUDIO_STOP - ok
Apr 13 23:58:53 dm900 enigma2[234]: DEMUX_STOP - audio - ok
Apr 13 23:58:53 dm900 kernel: stop AUDIO0 on dmx 0 pid:503
Apr 13 23:58:53 dm900 kernel: audio ch0: xpt stop
Apr 13 23:58:53 dm900 kernel: sync0 1fff no decoder left in sync handling so disable
Apr 13 23:58:53 dm900 kernel: sync0: was last pcroffset0 user so free it
Apr 13 23:58:53 dm900 kernel[206]: [19081.960784] stop AUDIO0 on dmx 0 pid:503
Apr 13 23:58:53 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:58:53 dm900 enigma2[234]: DEMUX_STOP - ttx - ok
Apr 13 23:58:53 dm900 enigma2[234]: start release channel timer
Apr 13 23:58:53 dm900 kernel[206]: [19081.960791] audio ch0: xpt stop
Apr 13 23:58:53 dm900 kernel[206]: [19081.960797] sync0 1fff no decoder left in sync handling so disable
Apr 13 23:58:53 dm900 kernel[206]: [19081.960801] sync0: was last pcroffset0 user so free it
Apr 13 23:58:53 dm900 enigma2[234]: [TeleText] service started
Apr 13 23:58:53 dm900 enigma2[234]: not pauseable.
Apr 13 23:58:53 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:53 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656733.08
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] remove channel 0x1b5ba38
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBLocalTimeHandler] remove channel 0x1b5ba38
Apr 13 23:58:53 dm900 enigma2[234]: [EPGC] remove channel 0x1b5ba38
Apr 13 23:58:53 dm900 enigma2[234]: [    0] preClose
Apr 13 23:58:53 dm900 enigma2[234]: allocate channel.. 041d:0001:00c00000
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] new channel 0x1b5ba38!
Apr 13 23:58:53 dm900 enigma2[234]: [    0] tune type 1
Apr 13 23:58:53 dm900 enigma2[234]: RotorCmd ffffffff, lastRotorCmd ffffffff
Apr 13 23:58:53 dm900 enigma2[234]: [    0] prepare_sat System DVB-S2 Freq 10773250H SR 22000000 INV Auto FEC 3/4 orbpos 192 modulation 8PSK pilot Auto, rolloff 0.20
Apr 13 23:58:53 dm900 enigma2[234]: [    0] tuning to 1023 mhz
Apr 13 23:58:53 dm900 enigma2[234]: OURSTATE: tuning
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] update oparm
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] lock 0
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] locked 0
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] set static current limiting
Apr 13 23:58:53 dm900 enigma2[234]: allocate Channel: res 0
Apr 13 23:58:53 dm900 enigma2[234]: allocate demux
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] invalidate current switch params
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] update current switch params
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] startTuneTimeout 5000
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] setFrontend 1
Apr 13 23:58:53 dm900 enigma2[234]: [    0] setting frontend
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] unlock 0
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] sleep 500ms
Apr 13 23:58:53 dm900 enigma2[234]: setInputSource input0 to A1
Apr 13 23:58:53 dm900 enigma2[234]: have 1 video stream(s) (03ff), and 1 audio stream(s) (0403), and the pcr pid is 03ff, and the text pid is 0023
Apr 13 23:58:53 dm900 enigma2[234]: allocate demux
Apr 13 23:58:53 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:58:53 dm900 kernel: start PCR0 on dmx 0 pid:3ff index 4
Apr 13 23:58:53 dm900 kernel: start AUDIO0 on dmx 0 pid:403 index 1
Apr 13 23:58:53 dm900 kernel: audio ch0: xpt start
Apr 13 23:58:53 dm900 kernel: sync0 is off so we enable it
Apr 13 23:58:53 dm900 kernel: sync0: allocated pcroffset 0
Apr 13 23:58:53 dm900 kernel: sync0: demux-index 0 pcr pid 3ff
Apr 13 23:58:53 dm900 kernel: sync0: using 4 as pcr pidchannel
Apr 13 23:58:53 dm900 kernel: audio ch0: AC3
Apr 13 23:58:53 dm900 kernel: audio ch0: decoder start
Apr 13 23:58:53 dm900 kernel: audio ch0 delay 18000 (200 ms)
Apr 13 23:58:53 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:53 dm900 kernel: start VIDEO0 on dmx 0 pid:3ff index 4
Apr 13 23:58:53 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:53 dm900 kernel[206]: [19081.979804] start PCR0 on dmx 0 pid:3ff index 4
Apr 13 23:58:53 dm900 enigma2[234]: decoder state: play, vpid=1023, apid=1027
Apr 13 23:58:53 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x3ff) - pcr - ok
Apr 13 23:58:53 dm900 enigma2[234]: DEMUX_START - pcr - ok
Apr 13 23:58:53 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x403) - audio - ok
Apr 13 23:58:53 dm900 enigma2[234]: DEMUX_START - audio - ok
Apr 13 23:58:53 dm900 enigma2[234]: AUDIO_SET_BYPASS(0) - ok
Apr 13 23:58:53 dm900 enigma2[234]: AUDIO_PAUSE - ok
Apr 13 23:58:53 dm900 enigma2[234]: AUDIO_PLAY - ok
Apr 13 23:58:53 dm900 enigma2[234]: Video Device: /dev/dvb/adapter0/video0
Apr 13 23:58:53 dm900 enigma2[234]: demux device: /dev/dvb/adapter0/demux0
Apr 13 23:58:53 dm900 enigma2[234]: VIDEO_SET_STREAMTYPE 1 - ok
Apr 13 23:58:53 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x3ff) - video - ok
Apr 13 23:58:53 dm900 enigma2[234]: DEMUX_START - video - ok
Apr 13 23:58:53 dm900 enigma2[234]: VIDEO_FREEZE - ok
Apr 13 23:58:53 dm900 kernel: VIDEO0: start decode
Apr 13 23:58:53 dm900 kernel[206]: [19081.980410] start AUDIO0 on dmx 0 pid:403 index 1
Apr 13 23:58:53 dm900 enigma2[234]: VIDEO_PLAY - ok
Apr 13 23:58:53 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x23) - ttx - ok
Apr 13 23:58:53 dm900 enigma2[234]: DEMUX_START - ttx - ok
Apr 13 23:58:53 dm900 enigma2[234]: VIDEO_SLOWMOTION(0) - ok
Apr 13 23:58:53 dm900 enigma2[234]: VIDEO_FAST_FORWARD(0) - ok
Apr 13 23:58:53 dm900 enigma2[234]: VIDEO_CONTINUE - ok
Apr 13 23:58:53 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:58:53 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:53 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656733.1
Apr 13 23:58:53 dm900 enigma2[234]: not pauseable.
Apr 13 23:58:53 dm900 kernel[206]: [19081.980417] audio ch0: xpt start
Apr 13 23:58:53 dm900 enigma2[234]: [HbbTV].aitInvalidated
Apr 13 23:58:53 dm900 kernel[206]: [19081.980453] sync0 is off so we enable it
Apr 13 23:58:53 dm900 kernel[206]: [19081.980458] sync0: allocated pcroffset 0
Apr 13 23:58:53 dm900 kernel[206]: [19081.980469] sync0: demux-index 0 pcr pid 3ff
Apr 13 23:58:53 dm900 kernel[206]: [19081.980481] sync0: using 4 as pcr pidchannel
Apr 13 23:58:53 dm900 kernel[206]: [19081.980517] audio ch0: AC3
Apr 13 23:58:53 dm900 kernel[206]: [19081.980832] audio ch0: decoder start
Apr 13 23:58:53 dm900 kernel[206]: [19081.980840] audio ch0 delay 18000 (200 ms)
Apr 13 23:58:53 dm900 kernel[206]: [19081.980849] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:53 dm900 kernel[206]: [19081.982106] start VIDEO0 on dmx 0 pid:3ff index 4
Apr 13 23:58:53 dm900 kernel[206]: [19081.982143] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:53 dm900 kernel[206]: [19081.987498] VIDEO0: start decode
Apr 13 23:58:53 dm900 kernel: audio ch0: first pts 0xa0db77c, stc 0x18033bbde, pcr valid
Apr 13 23:58:53 dm900 kernel[206]: [19082.080099] audio ch0: first pts 0xa0db77c, stc 0x18033bbde, pcr valid
Apr 13 23:58:53 dm900 kernel: audio ch0: lock
Apr 13 23:58:53 dm900 kernel: audio ch0: sample rate changed isr (48000)
Apr 13 23:58:53 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:53 dm900 kernel: audio ch0: pts error 0 PTS 0x0a0db77c, STC 0x8033c67c, type 0
Apr 13 23:58:53 dm900 kernel[206]: [19082.110247] audio ch0: lock
Apr 13 23:58:53 dm900 kernel[206]: [19082.110264] audio ch0: sample rate changed isr (48000)
Apr 13 23:58:53 dm900 kernel[206]: [19082.110286] audio ch0: tsm fail
Apr 13 23:58:53 dm900 kernel[206]: [19082.110293] audio ch0: pts error 0 PTS 0x0a0db77c, STC 0x8033c67c, type 0
Apr 13 23:58:53 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:53 dm900 kernel: audio ch0: pts error 0 PTS 0x0a0dc2bc, STC 0x8033d274, type 1
Apr 13 23:58:53 dm900 kernel[206]: [19082.144341] audio ch0: tsm fail
Apr 13 23:58:53 dm900 kernel[206]: [19082.144352] audio ch0: pts error 0 PTS 0x0a0dc2bc, STC 0x8033d274, type 1
Apr 13 23:58:53 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:53 dm900 kernel: audio ch0: pts error 0 PTS 0x0a0dcdfc, STC 0x0a0dc64c, type 1
Apr 13 23:58:53 dm900 kernel[206]: [19082.174772] audio ch0: tsm fail
Apr 13 23:58:53 dm900 kernel[206]: [19082.174791] audio ch0: pts error 0 PTS 0x0a0dcdfc, STC 0x0a0dc64c, type 1
Apr 13 23:58:53 dm900 enigma2[234]: [    0] fe event: status 0, freq 1023250, inversion off, m_tuning 1 F
Apr 13 23:58:53 dm900 enigma2[234]: [    0] fe event: status 1, freq 1023250, inversion off, m_tuning 1 N
Apr 13 23:58:53 dm900 enigma2[234]: [    0] fe event: status 1f, freq 1023250, inversion off, m_tuning 2 N
Apr 13 23:58:53 dm900 enigma2[234]: OURSTATE: ok
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] channel 0x1b5ba38 running
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBLocalTimeHandler] channel 0x1b5ba38 running
Apr 13 23:58:53 dm900 enigma2[234]: no version filtering
Apr 13 23:58:53 dm900 enigma2[234]: 0014:  70 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mask:  fc 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: [EPGC] channel 0x1b5ba38 running
Apr 13 23:58:53 dm900 enigma2[234]: [EPGC] next update in 9 min
Apr 13 23:58:53 dm900 enigma2[234]: stop release channel timer
Apr 13 23:58:53 dm900 enigma2[234]: no version filtering
Apr 13 23:58:53 dm900 enigma2[234]: 0012:  4e 52 6f 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: ok ... now we start!!
Apr 13 23:58:53 dm900 enigma2[234]: no version filtering
Apr 13 23:58:53 dm900 enigma2[234]: 0000:  00 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: + 1/1 TID 00
Apr 13 23:58:53 dm900 enigma2[234]: done!
Apr 13 23:58:53 dm900 enigma2[234]: PATready
Apr 13 23:58:53 dm900 enigma2[234]: use pmtpid 0063 for service_id 526f
Apr 13 23:58:53 dm900 enigma2[234]: no version filtering
Apr 13 23:58:53 dm900 enigma2[234]: 0063:  02 52 6f 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:53 dm900 enigma2[234]: 0000:  00 00 00 1f 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:53 dm900 enigma2[234]: + 1/1 TID 02
Apr 13 23:58:53 dm900 enigma2[234]: done!
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] new service 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] add demux 0 to slot 0 service 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAService] demux 0 mask 01
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCAServiceConn] send 57 bytes to '/tmp/camd.socket'
Apr 13 23:58:53 dm900 enigma2[234]: 9f 80 32 35 03 52 6f 05 00 1b 01 81 08 00 c0 00 00 04 1d 00 01 82 02 01 00 84 02 00 63 85 03 00 41 31 86 01 00 06 00 23 00 00 1b 03 ff 00 00 06 04 03 00 00 05 04 05 00 00
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCIInterfaces] addPMTHandler 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:53 dm900 enigma2[234]: [eDVBCIInterfaces] gotPMT
Apr 13 23:58:53 dm900 enigma2[234]: eventNewProgramInfo 0 0
Apr 13 23:58:53 dm900 enigma2[234]: start ait on pid 0405
Apr 13 23:58:53 dm900 enigma2[234]: no version filtering
Apr 13 23:58:53 dm900 enigma2[234]: 0405:  74 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:53 dm900 enigma2[234]: have 1 video stream(s) (03ff), and 1 audio stream(s) (0403), and the pcr pid is 03ff, and the text pid is 0023
Apr 13 23:58:53 dm900 enigma2[234]: decoder state: play, vpid=1023, apid=1027
Apr 13 23:58:53 dm900 enigma2[234]: [TeleText] serviceInfoChanged
Apr 13 23:58:53 dm900 enigma2[234]: [TeleText] TXT PID 35 DEMUX 0
Apr 13 23:58:53 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 35, 0])
Apr 13 23:58:53 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:53 dm900 enigma2[234]: 0063:  02 52 6f 05 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:58:53 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:53 dm900 kernel: VIDEO0: first pts 0xa0f3450
Apr 13 23:58:53 dm900 kernel: VIDEO0: resolution changed (1920 1080)
Apr 13 23:58:53 dm900 kernel[206]: [19082.374633] VIDEO0: first pts 0xa0f3450
Apr 13 23:58:53 dm900 kernel[206]: [19082.374667] VIDEO0: resolution changed (1920 1080)
Apr 13 23:58:53 dm900 kernel: audio ch0: status ready isr
Apr 13 23:58:53 dm900 kernel[206]: [19082.472829] audio ch0: status ready isr
Apr 13 23:58:53 dm900 enigma2[234]: [SEC-0] set dynamic current limiting
Apr 13 23:58:53 dm900 kernel: VIDEO0: enable tsm
Apr 13 23:58:53 dm900 kernel[206]: [19082.524100] VIDEO0: enable tsm
Apr 13 23:58:54 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:54 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:54 dm900 enigma2[234]: unhandled DVBAPI Video Event 32
Apr 13 23:58:54 dm900 enigma2[234]: +- 1/2 TID 4e
Apr 13 23:58:54 dm900 enigma2[234]: + 1/1 TID 74
Apr 13 23:58:54 dm900 enigma2[234]: done!
Apr 13 23:58:54 dm900 enigma2[234]: [eHbbtv] aitChanged pid=0x405
Apr 13 23:58:54 dm900 enigma2[234]: [eOipfApplication] Profile 0000, Version 1.1.1
Apr 13 23:58:54 dm900 enigma2[234]: [eOipfApplication] deu:QVC HD
Apr 13 23:58:54 dm900 enigma2[234]: [eHbbtv] New Application: QVC HD (orgid=33, appid=13, urlBase=https://hbbtv.qvc.de/hbbtv/qvc/, initalPath=index.php, controlCode=1, visibility=2)
Apr 13 23:58:54 dm900 enigma2[234]: [eHbbtv] Resolving Application Locator: dvb://current.ait/21.d
Apr 13 23:58:54 dm900 enigma2[234]: [eHbbtv] We have 1 app(s) for service 1.1053.21103
Apr 13 23:58:54 dm900 enigma2[234]: [eHbbtv] 'dvb://current.ait/21.d' resolved to 'https://hbbtv.qvc.de/hbbtv/qvc/index.php'
Apr 13 23:58:54 dm900 enigma2[234]: [HbbTV].readButtonApplicationReady, appid=21.d
Apr 13 23:58:54 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:54 dm900 enigma2[234]: 0405:  74 00 00 01 00 00
Apr 13 23:58:54 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:58:54 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:54 dm900 enigma2[234]: sdt update done!
Apr 13 23:58:55 dm900 enigma2[234]: ++ 2/2 TID 4e
Apr 13 23:58:55 dm900 enigma2[234]: done!
Apr 13 23:58:55 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:55 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656735.21
Apr 13 23:58:55 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:55 dm900 enigma2[234]: 0012:  4e 52 6f 35 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:55 dm900 enigma2[234]: [InfoBar] quickSelectGlobal key:  right
Apr 13 23:58:55 dm900 enigma2[234]: [InfoBar] exec from Screens.InfoBar import InfoBar
Apr 13 23:58:55 dm900 enigma2[234]: if InfoBar and InfoBar.instance:
Apr 13 23:58:55 dm900 enigma2[234]:         InfoBar.zapDown(InfoBar.instance)
Apr 13 23:58:55 dm900 enigma2[234]: playing 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] free service 1:0:19:526F:41D:1:C00000:0:0:0:
Apr 13 23:58:55 dm900 enigma2[234]: [eDBusInterface::Private::sendEvent 0
Apr 13 23:58:55 dm900 enigma2[234]: [TeleText] service stopped
Apr 13 23:58:55 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 0, 0])
Apr 13 23:58:55 dm900 enigma2[234]: disable dvb subtitles
Apr 13 23:58:55 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:58:55 dm900 enigma2[234]: decoder state: play, vpid=-1, apid=-1
Apr 13 23:58:55 dm900 enigma2[234]: DEMUX_STOP - pcr - ok
Apr 13 23:58:55 dm900 kernel: stop PCR0 on dmx 0 pid:3ff
Apr 13 23:58:55 dm900 kernel: stop VIDEO0 on dmx 0 pid:3ff
Apr 13 23:58:55 dm900 kernel: VIDEO0: stop decode
Apr 13 23:58:55 dm900 kernel[206]: [19084.413460] stop PCR0 on dmx 0 pid:3ff
Apr 13 23:58:55 dm900 kernel: audio ch0: decoder stop
Apr 13 23:58:55 dm900 kernel[206]: [19084.413652] stop VIDEO0 on dmx 0 pid:3ff
Apr 13 23:58:55 dm900 enigma2[234]: DEMUX_STOP - video - ok
Apr 13 23:58:55 dm900 enigma2[234]: VIDEO_STOP - ok
Apr 13 23:58:55 dm900 kernel[206]: [19084.414461] VIDEO0: stop decode
Apr 13 23:58:55 dm900 kernel[206]: [19084.414955] audio ch0: decoder stop
Apr 13 23:58:55 dm900 enigma2[234]: AUDIO_STOP - ok
Apr 13 23:58:55 dm900 enigma2[234]: DEMUX_STOP - audio - ok
Apr 13 23:58:55 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:58:55 dm900 kernel: stop AUDIO0 on dmx 0 pid:403
Apr 13 23:58:55 dm900 kernel: audio ch0: xpt stop
Apr 13 23:58:55 dm900 kernel: sync0 1fff no decoder left in sync handling so disable
Apr 13 23:58:55 dm900 kernel: sync0: was last pcroffset0 user so free it
Apr 13 23:58:55 dm900 kernel[206]: [19084.416608] stop AUDIO0 on dmx 0 pid:403
Apr 13 23:58:55 dm900 enigma2[234]: DEMUX_STOP - ttx - ok
Apr 13 23:58:55 dm900 enigma2[234]: start release channel timer
Apr 13 23:58:55 dm900 kernel[206]: [19084.416617] audio ch0: xpt stop
Apr 13 23:58:55 dm900 kernel[206]: [19084.416626] sync0 1fff no decoder left in sync handling so disable
Apr 13 23:58:55 dm900 kernel[206]: [19084.416631] sync0: was last pcroffset0 user so free it
Apr 13 23:58:55 dm900 enigma2[234]: [TeleText] service started
Apr 13 23:58:55 dm900 enigma2[234]: not pauseable.
Apr 13 23:58:55 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:55 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656735.53
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] remove channel 0x1b5ba38
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBLocalTimeHandler] remove channel 0x1b5ba38
Apr 13 23:58:55 dm900 enigma2[234]: [EPGC] remove channel 0x1b5ba38
Apr 13 23:58:55 dm900 enigma2[234]: [    0] preClose
Apr 13 23:58:55 dm900 enigma2[234]: allocate channel.. 041f:0001:00c00000
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] new channel 0x1b5ba38!
Apr 13 23:58:55 dm900 enigma2[234]: [    0] tune type 1
Apr 13 23:58:55 dm900 enigma2[234]: RotorCmd ffffffff, lastRotorCmd ffffffff
Apr 13 23:58:55 dm900 enigma2[234]: [    0] prepare_sat System DVB-S2 Freq 10802750H SR 22000000 INV Auto FEC 3/4 orbpos 192 modulation 8PSK pilot Auto, rolloff 0.35
Apr 13 23:58:55 dm900 enigma2[234]: [    0] tuning to 1052 mhz
Apr 13 23:58:55 dm900 enigma2[234]: OURSTATE: tuning
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] update oparm
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] lock 0
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] locked 0
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] set static current limiting
Apr 13 23:58:55 dm900 enigma2[234]: allocate Channel: res 0
Apr 13 23:58:55 dm900 enigma2[234]: allocate demux
Apr 13 23:58:55 dm900 kernel: start PCR0 on dmx 0 pid:4ff index 4
Apr 13 23:58:55 dm900 kernel: start AUDIO0 on dmx 0 pid:503 index 1
Apr 13 23:58:55 dm900 kernel: audio ch0: xpt start
Apr 13 23:58:55 dm900 kernel: sync0 is off so we enable it
Apr 13 23:58:55 dm900 kernel: sync0: allocated pcroffset 0
Apr 13 23:58:55 dm900 kernel: sync0: demux-index 0 pcr pid 4ff
Apr 13 23:58:55 dm900 kernel: sync0: using 4 as pcr pidchannel
Apr 13 23:58:55 dm900 kernel: audio ch0: AC3
Apr 13 23:58:55 dm900 kernel: audio ch0: decoder start
Apr 13 23:58:55 dm900 kernel: audio ch0 delay 18000 (200 ms)
Apr 13 23:58:55 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:55 dm900 kernel: start VIDEO0 on dmx 0 pid:4ff index 4
Apr 13 23:58:55 dm900 kernel: VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:55 dm900 kernel[206]: [19084.431598] start PCR0 on dmx 0 pid:4ff index 4
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] invalidate current switch params
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] update current switch params
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] startTuneTimeout 5000
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] setFrontend 1
Apr 13 23:58:55 dm900 enigma2[234]: [    0] setting frontend
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] unlock 0
Apr 13 23:58:55 dm900 enigma2[234]: [SEC-0] sleep 500ms
Apr 13 23:58:55 dm900 enigma2[234]: setInputSource input0 to A1
Apr 13 23:58:55 dm900 enigma2[234]: have 1 video stream(s) (04ff), and 1 audio stream(s) (0503), and the pcr pid is 04ff, and the text pid is 0024
Apr 13 23:58:55 dm900 enigma2[234]: allocate demux
Apr 13 23:58:55 dm900 enigma2[234]: disable teletext subtitles page ffffffffffffffff (und)
Apr 13 23:58:55 dm900 enigma2[234]: decoder state: play, vpid=1279, apid=1283
Apr 13 23:58:55 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x4ff) - pcr - ok
Apr 13 23:58:55 dm900 enigma2[234]: DEMUX_START - pcr - ok
Apr 13 23:58:55 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x503) - audio - ok
Apr 13 23:58:55 dm900 enigma2[234]: DEMUX_START - audio - ok
Apr 13 23:58:55 dm900 enigma2[234]: AUDIO_SET_BYPASS(0) - ok
Apr 13 23:58:55 dm900 enigma2[234]: AUDIO_PAUSE - ok
Apr 13 23:58:55 dm900 enigma2[234]: AUDIO_PLAY - ok
Apr 13 23:58:55 dm900 enigma2[234]: Video Device: /dev/dvb/adapter0/video0
Apr 13 23:58:55 dm900 enigma2[234]: demux device: /dev/dvb/adapter0/demux0
Apr 13 23:58:55 dm900 enigma2[234]: VIDEO_SET_STREAMTYPE 1 - ok
Apr 13 23:58:55 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x4ff) - video - ok
Apr 13 23:58:55 dm900 enigma2[234]: DEMUX_START - video - ok
Apr 13 23:58:55 dm900 enigma2[234]: VIDEO_FREEZE - ok
Apr 13 23:58:55 dm900 kernel: VIDEO0: start decode
Apr 13 23:58:55 dm900 kernel[206]: [19084.431971] start AUDIO0 on dmx 0 pid:503 index 1
Apr 13 23:58:55 dm900 enigma2[234]: VIDEO_PLAY - ok
Apr 13 23:58:55 dm900 enigma2[234]: DMX_SET_PES_FILTER(0x24) - ttx - ok
Apr 13 23:58:55 dm900 enigma2[234]: DEMUX_START - ttx - ok
Apr 13 23:58:55 dm900 enigma2[234]: VIDEO_SLOWMOTION(0) - ok
Apr 13 23:58:55 dm900 enigma2[234]: VIDEO_FAST_FORWARD(0) - ok
Apr 13 23:58:55 dm900 enigma2[234]: VIDEO_CONTINUE - ok
Apr 13 23:58:55 dm900 enigma2[234]: AUDIO_CONTINUE - ok
Apr 13 23:58:55 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:55 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656735.55
Apr 13 23:58:55 dm900 enigma2[234]: not pauseable.
Apr 13 23:58:55 dm900 enigma2[234]: [HbbTV].aitInvalidated
Apr 13 23:58:55 dm900 kernel[206]: [19084.431977] audio ch0: xpt start
Apr 13 23:58:55 dm900 kernel[206]: [19084.431999] sync0 is off so we enable it
Apr 13 23:58:55 dm900 kernel[206]: [19084.432005] sync0: allocated pcroffset 0
Apr 13 23:58:55 dm900 kernel[206]: [19084.432020] sync0: demux-index 0 pcr pid 4ff
Apr 13 23:58:55 dm900 kernel[206]: [19084.432030] sync0: using 4 as pcr pidchannel
Apr 13 23:58:55 dm900 kernel[206]: [19084.432049] audio ch0: AC3
Apr 13 23:58:55 dm900 kernel[206]: [19084.432339] audio ch0: decoder start
Apr 13 23:58:55 dm900 kernel[206]: [19084.432349] audio ch0 delay 18000 (200 ms)
Apr 13 23:58:55 dm900 kernel[206]: [19084.432355] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:55 dm900 kernel[206]: [19084.433312] start VIDEO0 on dmx 0 pid:4ff index 4
Apr 13 23:58:55 dm900 kernel[206]: [19084.433349] VIDEO0: total delay: 18000 (200 ms)
Apr 13 23:58:55 dm900 kernel[206]: [19084.438643] VIDEO0: start decode
Apr 13 23:58:55 dm900 enigma2[234]: [    0] fe event: status 0, freq 1052750, inversion off, m_tuning 1 F
Apr 13 23:58:55 dm900 kernel: audio ch0: first pts 0x17c0d3138, stc 0x63d4a92, pcr valid
Apr 13 23:58:55 dm900 kernel[206]: [19084.530847] audio ch0: first pts 0x17c0d3138, stc 0x63d4a92, pcr valid
Apr 13 23:58:55 dm900 enigma2[234]: [    0] fe event: status 1, freq 1052750, inversion off, m_tuning 1 N
Apr 13 23:58:55 dm900 kernel: audio ch0: lock
Apr 13 23:58:55 dm900 kernel: audio ch0: sample rate changed isr (48000)
Apr 13 23:58:55 dm900 kernel: audio ch0: tsm fail
Apr 13 23:58:55 dm900 kernel[206]: [19084.561271] audio ch0: lock
Apr 13 23:58:55 dm900 kernel[206]: [19084.561302] audio ch0: sample rate changed isr (48000)
Apr 13 23:58:55 dm900 kernel[206]: [19084.561327] audio ch0: tsm fail
Apr 13 23:58:55 dm900 enigma2[234]: [    0] fe event: status 1f, freq 1052750, inversion off, m_tuning 2 N
Apr 13 23:58:55 dm900 enigma2[234]: OURSTATE: ok
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] channel 0x1b5ba38 running
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBLocalTimeHandler] channel 0x1b5ba38 running
Apr 13 23:58:55 dm900 enigma2[234]: no version filtering
Apr 13 23:58:55 dm900 enigma2[234]: 0014:  70 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mask:  fc 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: [EPGC] channel 0x1b5ba38 running
Apr 13 23:58:55 dm900 enigma2[234]: [EPGC] next update in 58 min
Apr 13 23:58:55 dm900 enigma2[234]: stop release channel timer
Apr 13 23:58:55 dm900 enigma2[234]: no version filtering
Apr 13 23:58:55 dm900 enigma2[234]: 0012:  4e 15 80 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: ok ... now we start!!
Apr 13 23:58:55 dm900 enigma2[234]: no version filtering
Apr 13 23:58:55 dm900 enigma2[234]: 0000:  00 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: + 1/1 TID 00
Apr 13 23:58:55 dm900 enigma2[234]: done!
Apr 13 23:58:55 dm900 enigma2[234]: PATready
Apr 13 23:58:55 dm900 enigma2[234]: use pmtpid 0064 for service_id 1580
Apr 13 23:58:55 dm900 enigma2[234]: no version filtering
Apr 13 23:58:55 dm900 enigma2[234]: 0064:  02 15 80 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mask:  ff ff ff 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:55 dm900 enigma2[234]: 0000:  00 00 00 1d 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:55 dm900 enigma2[234]: + 1/1 TID 02
Apr 13 23:58:55 dm900 enigma2[234]: done!
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] new service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] add demux 0 to slot 0 service 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAService] demux 0 mask 01
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCAServiceConn] send 57 bytes to '/tmp/camd.socket'
Apr 13 23:58:55 dm900 enigma2[234]: 9f 80 32 35 03 15 80 03 00 1b 01 81 08 00 c0 00 00 04 1f 00 01 82 02 01 00 84 02 00 64 85 03 00 41 31 86 01 00 06 00 24 00 00 1b 04 ff 00 00 06 05 03 00 00 05 05 05 00 00
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCIInterfaces] addPMTHandler 1:0:19:1580:41F:1:C00000:0:0:0:
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBCIInterfaces] gotPMT
Apr 13 23:58:55 dm900 enigma2[234]: eventNewProgramInfo 0 0
Apr 13 23:58:55 dm900 enigma2[234]: start ait on pid 0505
Apr 13 23:58:55 dm900 enigma2[234]: no version filtering
Apr 13 23:58:55 dm900 enigma2[234]: 0505:  74 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mask:  ff 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mode:  00 00 00 00 00 00
Apr 13 23:58:55 dm900 enigma2[234]: have 1 video stream(s) (04ff), and 1 audio stream(s) (0503), and the pcr pid is 04ff, and the text pid is 0024
Apr 13 23:58:55 dm900 enigma2[234]: decoder state: play, vpid=1279, apid=1283
Apr 13 23:58:55 dm900 enigma2[234]: [TeleText] serviceInfoChanged
Apr 13 23:58:55 dm900 enigma2[234]: [TeleText] TXT PID 36 DEMUX 0
Apr 13 23:58:55 dm900 enigma2[234]: [TeleText] send array('B', [1, 0, 36, 0])
Apr 13 23:58:55 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:55 dm900 enigma2[234]: 0064:  02 15 80 03 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:58:55 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:55 dm900 enigma2[234]: [eDVBLocalTimeHandler] Receiver time is 'Fri Apr 13 23:58:55 2018'
Apr 13 23:58:46 dm900 enigma2[234]: [eDVBLocalTimeHandler] Transponder time is 'Fri Apr 13 23:58:46 2018'
Apr 13 23:58:46 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff is -9
Apr 13 23:58:46 dm900 enigma2[234]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Apr 13 23:58:46 dm900 enigma2[234]: [eDVBLocalTimeHandler] update RTC
Apr 13 23:58:46 dm900 enigma2[234]: [eDVBLocalTimeHandler] time_difference is -9
Apr 13 23:58:46 dm900 enigma2[234]: [eDVBLocalTimeHandler] update Linux Time
Apr 13 23:58:46 dm900 systemd[1]: Time has been changed
Apr 13 23:58:55 dm900 systemd[1]: Time has been changed
Apr 13 23:58:56 dm900 enigma2[234]: [SEC-0] set dynamic current limiting
Apr 13 23:58:56 dm900 enigma2[234]: + 1/1 TID 74
Apr 13 23:58:56 dm900 enigma2[234]: done!
Apr 13 23:58:56 dm900 enigma2[234]: [eHbbtv] aitChanged pid=0x505
Apr 13 23:58:56 dm900 enigma2[234]: [eOipfApplication] Profile 0000, Version 1.1.1
Apr 13 23:58:56 dm900 enigma2[234]: [eOipfApplication] deu:QVC Plus
Apr 13 23:58:56 dm900 enigma2[234]: [eHbbtv] New Application: QVC Plus (orgid=33, appid=14, urlBase=https://hbbtv.qvc.de/hbbtv/qvc_plus/, initalPath=index.php, controlCode=1, visibility=2)
Apr 13 23:58:56 dm900 enigma2[234]: [eHbbtv] Resolving Application Locator: dvb://current.ait/21.e
Apr 13 23:58:56 dm900 enigma2[234]: [eHbbtv] We have 1 app(s) for service 1.1055.5504
Apr 13 23:58:56 dm900 enigma2[234]: [eHbbtv] 'dvb://current.ait/21.e' resolved to 'https://hbbtv.qvc.de/hbbtv/qvc_plus/index.php'
Apr 13 23:58:56 dm900 enigma2[234]: [HbbTV].readButtonApplicationReady, appid=21.e
Apr 13 23:58:56 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:56 dm900 enigma2[234]: 0505:  74 00 00 01 00 00
Apr 13 23:58:56 dm900 enigma2[234]: mask:  ff 00 00 3f 00 00
Apr 13 23:58:56 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:58:56 dm900 enigma2[234]: sdt update done!
Apr 13 23:58:56 dm900 enigma2[234]: +- 1/2 TID 4e
Apr 13 23:58:57 dm900 kernel: VIDEO0: first pts 0x17c10044e
Apr 13 23:58:57 dm900 kernel: VIDEO0: resolution changed (1440 1080)
Apr 13 23:58:57 dm900 kernel[206]: [19086.114653] VIDEO0: first pts 0x17c10044e
Apr 13 23:58:57 dm900 kernel[206]: [19086.114683] VIDEO0: resolution changed (1440 1080)
Apr 13 23:58:57 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:57 dm900 enigma2[234]: VIDEO_GET_EVENT - ok
Apr 13 23:58:57 dm900 enigma2[234]: unhandled DVBAPI Video Event 32
Apr 13 23:58:57 dm900 kernel: VIDEO0: enable tsm
Apr 13 23:58:57 dm900 kernel[206]: [19086.274130] VIDEO0: enable tsm
Apr 13 23:58:57 dm900 enigma2[234]: ++ 2/2 TID 4e
Apr 13 23:58:57 dm900 enigma2[234]: done!
Apr 13 23:58:57 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:58:57 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656737.46
Apr 13 23:58:57 dm900 enigma2[234]: doing version filtering
Apr 13 23:58:57 dm900 enigma2[234]: 0012:  4e 15 80 2b 00 00
Apr 13 23:58:57 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:58:57 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:59:04 dm900 enigma2[234]: It's now  Fr 13 Apr 2018 23:59:04 CEST
Apr 13 23:59:04 dm900 enigma2[234]: next real activation is Sa 14 Apr 2018 19:29:40 CEST
Apr 13 23:59:04 dm900 enigma2[234]: [timer.py] next activation: 1523656844 (in 99023 ms)
Apr 13 23:59:13 dm900 enigma2[234]: nr_read 129492 nr_write 165531
Apr 13 23:59:13 dm900 enigma2[234]: sum 295023 prev_sum 295023
Apr 13 23:59:13 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:59:13 dm900 enigma2[234]: [IDLE] 149.960132122 300 False
Apr 13 23:59:13 dm900 enigma2[234]: nr_read 249 nr_write 1
Apr 13 23:59:13 dm900 enigma2[234]: sum 250 prev_sum 250
Apr 13 23:59:13 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:59:13 dm900 enigma2[234]: [IDLE] 19049.5874801 300 True
Apr 13 23:59:15 dm900 enigma2[234]: no ancillary data in audio stream... abort radiotext pes parser
Apr 13 23:59:38 dm900 enigma2[234]: +- 1/2 TID 4e
Apr 13 23:59:39 dm900 enigma2[234]: ++ 2/2 TID 4e
Apr 13 23:59:39 dm900 enigma2[234]: done!
Apr 13 23:59:39 dm900 enigma2[234]: W/  [InfoBar.__servicePlaying] :: <no detail>
Apr 13 23:59:39 dm900 enigma2[234]: I/  [InfoBar.setPlaybackState] :: None None 1523656779.26
Apr 13 23:59:39 dm900 enigma2[234]: doing version filtering
Apr 13 23:59:39 dm900 enigma2[234]: 0012:  4e 15 80 2d 00 00
Apr 13 23:59:39 dm900 enigma2[234]: mask:  ff ff ff 3f 00 00
Apr 13 23:59:39 dm900 enigma2[234]: mode:  00 00 00 3e 00 00
Apr 13 23:59:41 dm900 enigma2[234]: [EPGC] cleanupOutdated
Apr 13 23:59:43 dm900 enigma2[234]: nr_read 129492 nr_write 165531
Apr 13 23:59:43 dm900 enigma2[234]: sum 295023 prev_sum 295023
Apr 13 23:59:43 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:59:43 dm900 enigma2[234]: [IDLE] 179.960108995 300 False
Apr 13 23:59:43 dm900 enigma2[234]: nr_read 249 nr_write 1
Apr 13 23:59:43 dm900 enigma2[234]: sum 250 prev_sum 250
Apr 13 23:59:43 dm900 enigma2[234]: hdd IDLE!
Apr 13 23:59:43 dm900 enigma2[234]: [IDLE] 19079.5872071 300 True
Apr 13 23:59:43 dm900 enigma2[234]: It's now  Fr 13 Apr 2018 23:59:43 CEST
Apr 13 23:59:43 dm900 enigma2[234]: [timer.py] next activation: 1523656883 (in 99024 ms)
Apr 13 23:59:54 dm900 systemd[1]: Time has been changed