-- Logs begin at Thu 1970-01-01 01:00:00 CET. -- Oct 03 09:27:09 dm900 enigma2[271]: [timer.py] next activation: 1507015729 (in 99020 ms) Oct 03 09:27:13 dm900 enigma2[271]: I/ [HTTPAuthResource.isAuthenticated] :: Local auth is disabled - Bypassing Authcheck because host '::ffff:192.168.178.20/128' is local! Oct 03 09:27:13 dm900 systemd[1]: Started 192.168.178.20:62801. Oct 03 09:27:13 dm900 enigma2[271]: nr_read 908 nr_write 1544 Oct 03 09:27:13 dm900 enigma2[271]: sum 2452 prev_sum 2449 Oct 03 09:27:13 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:27:13 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:27:13 dm900 enigma2[271]: [EPGC] cleanupOutdated Oct 03 09:27:13 dm900 systemd[1]: Started 192.168.178.20:62802. Oct 03 09:27:14 dm900 login[1028]: ROOT LOGIN on '/dev/pts/0' from '[::ffff:192.168.178.20]:62802' Oct 03 09:27:25 dm900 enigma2[271]: nr_read 908 nr_write 1544 Oct 03 09:27:25 dm900 enigma2[271]: sum 2452 prev_sum 2452 Oct 03 09:27:25 dm900 enigma2[271]: hdd IDLE! Oct 03 09:27:25 dm900 enigma2[271]: [IDLE] 11.9998967648 120 False Oct 03 09:27:26 dm900 enigma2[271]: no ancillary data in audio stream... abort radiotext pes parser Oct 03 09:27:30 dm900 enigma2[271]: action -> InfobarInstantRecord instantRecord Oct 03 09:27:30 dm900 enigma2[271]: self.recording: [] Oct 03 09:27:30 dm900 enigma2[271]: I/ [TemplatedListFonts.__init__] :: Bigger: 24, Regular Oct 03 09:27:30 dm900 enigma2[271]: I/ [TemplatedListFonts.__init__] :: Big: 22, Regular Oct 03 09:27:30 dm900 enigma2[271]: I/ [TemplatedListFonts.__init__] :: Medium: 20, Regular Oct 03 09:27:30 dm900 enigma2[271]: I/ [TemplatedListFonts.__init__] :: Small: 18, Regular Oct 03 09:27:30 dm900 enigma2[271]: I/ [TemplatedListFonts.__init__] :: Smaller: 14, Regular Oct 03 09:27:30 dm900 enigma2[271]: create buffer for widget 650 x 328 Oct 03 09:27:33 dm900 enigma2[271]: action -> WizardActions ok Oct 03 09:27:33 dm900 enigma2[271]: I/ [InfoBar.__restoreState] :: Oct 03 09:27:33 dm900 enigma2[271]: pre: Oct 03 09:27:33 dm900 enigma2[271]: [] Oct 03 09:27:33 dm900 enigma2[271]: [TIMER] record time changed, start prepare is now: Tue Oct 3 09:27:13 2017 Oct 03 09:27:33 dm900 enigma2[271]: sanitycheck Oct 03 09:27:33 dm900 enigma2[271]: check Oct 03 09:27:33 dm900 enigma2[271]: checkTimerlist Oct 03 09:27:33 dm900 enigma2[271]: [Timer] Record Oct 03 09:27:33 dm900 enigma2[271]: ProcessRepeated Oct 03 09:27:33 dm900 enigma2[271]: It's now Di 03 Okt 2017 09:27:33 CEST Oct 03 09:27:33 dm900 enigma2[271]: [TIMER] activating state 1 Oct 03 09:27:33 dm900 enigma2[271]: recording service: > Oct 03 09:27:33 dm900 enigma2[271]: record: 0 Oct 03 09:27:33 dm900 enigma2[271]: [add callback] Oct 03 09:27:33 dm900 enigma2[271]: begin_date: 20171003 0927 Oct 03 09:27:33 dm900 enigma2[271]: service_name: VOX HD Oct 03 09:27:33 dm900 enigma2[271]: name: Verklag mich doch! Oct 03 09:27:33 dm900 enigma2[271]: description: Oct 03 09:27:33 dm900 enigma2[271]: [TIMER] Filename calculated as: '/media/hdd/movie/20171003 0927 - VOX HD - Verklag mich doch!.ts' Oct 03 09:27:33 dm900 enigma2[271]: use cached_channel Oct 03 09:27:33 dm900 enigma2[271]: allocate Channel: res 0 Oct 03 09:27:33 dm900 enigma2[271]: RECORD service event 7 Oct 03 09:27:33 dm900 enigma2[271]: tuned.. Oct 03 09:27:33 dm900 enigma2[271]: no version filtering Oct 03 09:27:33 dm900 enigma2[271]: 0012: 4e ef 11 00 00 00 Oct 03 09:27:33 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:27:33 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:27:33 dm900 enigma2[271]: [TIMER] record event 2 Oct 03 09:27:33 dm900 enigma2[271]: ok ... now we start!! Oct 03 09:27:33 dm900 enigma2[271]: no version filtering Oct 03 09:27:33 dm900 enigma2[271]: 0000: 00 00 00 00 00 00 Oct 03 09:27:33 dm900 enigma2[271]: mask: ff 00 00 00 00 00 Oct 03 09:27:33 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:27:33 dm900 enigma2[271]: [eDVBCAService] add demux 0 to slot 1 service 1:0:19:EF11:421:1:C00000:0:0:0: Oct 03 09:27:33 dm900 enigma2[271]: [eDVBCIInterfaces] addPMTHandler 1:0:19:EF11:421:1:C00000:0:0:0: Oct 03 09:27:33 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:27:33 dm900 enigma2[271]: RECORD service event 5 Oct 03 09:27:33 dm900 enigma2[271]: [TIMER] record event 6 Oct 03 09:27:33 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:27:33 dm900 enigma2[271]: RECORD service event 6 Oct 03 09:27:34 dm900 enigma2[271]: [eDVBMetaParser::updateMetaDb] database meta info outdated, updating! Oct 03 09:27:34 dm900 enigma2[271]: [eDVBMetaParser::updateMetaDb] setting tags to Oct 03 09:27:34 dm900 enigma2[271]: [eMediaDatabase] Error in query: Oct 03 09:27:34 dm900 enigma2[271]: driverText: Oct 03 09:27:34 dm900 enigma2[271]: databaseText: Oct 03 09:27:34 dm900 enigma2[271]: query epg event id 7852 Oct 03 09:27:34 dm900 enigma2[271]: found event.. store to disc Oct 03 09:27:34 dm900 enigma2[271]: [TIMER] prepare ok, waiting for begin Oct 03 09:27:34 dm900 enigma2[271]: [TIMER] activating state 2 Oct 03 09:27:34 dm900 enigma2[271]: [TIMER] start recording Oct 03 09:27:34 dm900 enigma2[271]: [TIMER] record event 0 Oct 03 09:27:34 dm900 enigma2[271]: AddPopup, id = None domain = RecordTimer Oct 03 09:27:34 dm900 enigma2[271]: Recording to /media/hdd/movie/20171003 0927 - VOX HD - Verklag mich doch!.ts... Oct 03 09:27:34 dm900 enigma2[271]: start recording... Oct 03 09:27:34 dm900 enigma2[271]: RECORD: have 1 video stream(s) (01ff), and 1 audio stream(s) (0203), and the pcr pid is 01ff, and the text pid is 0021 Oct 03 09:27:34 dm900 enigma2[271]: ADD PID: 0000 Oct 03 09:27:34 dm900 enigma2[271]: ADD PID: 0021 Oct 03 09:27:34 dm900 enigma2[271]: ADD PID: 01ff Oct 03 09:27:34 dm900 enigma2[271]: ADD PID: 0203 Oct 03 09:27:34 dm900 enigma2[271]: before: 1 Oct 03 09:27:34 dm900 enigma2[271]: after: 1 Oct 03 09:27:34 dm900 enigma2[271]: [TIMER] record event 4 Oct 03 09:27:34 dm900 enigma2[271]: next real activation is Di 03 Okt 2017 09:50:00 CEST Oct 03 09:27:34 dm900 enigma2[271]: [timer.py] next activation: 1507015754 (in 99886 ms) Oct 03 09:27:34 dm900 enigma2[271]: setIoPrio realtime level 7 ok Oct 03 09:27:34 dm900 enigma2[271]: eRecordFileThread (direct io) START Oct 03 09:27:34 dm900 enigma2[271]: record write thread succesfully created! Oct 03 09:27:34 dm900 enigma2[271]: setIoPrio realtime level 7 ok Oct 03 09:27:34 dm900 enigma2[271]: after: Oct 03 09:27:34 dm900 enigma2[271]: [] Oct 03 09:27:34 dm900 enigma2[271]: [NotificationQueue::popNotification] domain RecordTimer deferred_callable: False Oct 03 09:27:34 dm900 enigma2[271]: create buffer for widget 600 x 200 Oct 03 09:27:34 dm900 enigma2[271]: -+ 1/2 TID 4e Oct 03 09:27:34 dm900 enigma2[271]: + 1/1 TID 00 Oct 03 09:27:34 dm900 enigma2[271]: done! Oct 03 09:27:34 dm900 enigma2[271]: PATready Oct 03 09:27:34 dm900 enigma2[271]: use pmtpid 0061 for service_id ef11 Oct 03 09:27:34 dm900 enigma2[271]: no version filtering Oct 03 09:27:34 dm900 enigma2[271]: 0061: 02 ef 11 00 00 00 Oct 03 09:27:34 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:27:34 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:27:34 dm900 enigma2[271]: doing version filtering Oct 03 09:27:34 dm900 enigma2[271]: 0000: 00 00 00 0d 00 00 Oct 03 09:27:34 dm900 enigma2[271]: mask: ff 00 00 3f 00 00 Oct 03 09:27:34 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:27:34 dm900 enigma2[271]: ++ 2/2 TID 4e Oct 03 09:27:34 dm900 enigma2[271]: done! Oct 03 09:27:34 dm900 enigma2[271]: event id of now EIT event matched record event... Oct 03 09:27:34 dm900 enigma2[271]: written EIT based /media/hdd/movie/20171003 0927 - VOX HD - Verklag mich doch!.eit Oct 03 09:27:34 dm900 enigma2[271]: pcr of eit change: 3ffec948 Oct 03 09:27:34 dm900 enigma2[271]: [eDVBServiceRecord] now running: Verklag mich doch! (3600 seconds) (RECORDING) Oct 03 09:27:34 dm900 enigma2[271]: [TIMER] record event 9 Oct 03 09:27:34 dm900 enigma2[271]: doing version filtering Oct 03 09:27:34 dm900 enigma2[271]: 0012: 4e ef 11 3f 00 00 Oct 03 09:27:34 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:27:34 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:27:34 dm900 enigma2[271]: + 1/1 TID 02 Oct 03 09:27:34 dm900 enigma2[271]: done! Oct 03 09:27:34 dm900 enigma2[271]: HBBTV broadcast data on pid 0206 Oct 03 09:27:34 dm900 enigma2[271]: [eDVBCAService] don't send the same capmt twice Oct 03 09:27:34 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:27:34 dm900 enigma2[271]: RECORD service event 5 Oct 03 09:27:34 dm900 enigma2[271]: start recording... Oct 03 09:27:34 dm900 enigma2[271]: RECORD: have 1 video stream(s) (01ff), and 1 audio stream(s) (0203) (0031), and the pcr pid is 01ff, and the text pid is 0021 Oct 03 09:27:34 dm900 enigma2[271]: ADD PID: 0031 Oct 03 09:27:34 dm900 enigma2[271]: ADD PID: 0061 Oct 03 09:27:34 dm900 enigma2[271]: [TIMER] record event 4 Oct 03 09:27:34 dm900 enigma2[271]: [TIMER] record event 6 Oct 03 09:27:34 dm900 enigma2[271]: doing version filtering Oct 03 09:27:34 dm900 enigma2[271]: 0061: 02 ef 11 01 00 00 Oct 03 09:27:34 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:27:34 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:27:36 dm900 enigma2[271]: Timeout! Oct 03 09:27:36 dm900 enigma2[271]: I/ [InfoBar.__restoreState] :: Oct 03 09:27:37 dm900 enigma2[271]: nr_read 913 nr_write 1579 Oct 03 09:27:37 dm900 enigma2[271]: sum 2492 prev_sum 2452 Oct 03 09:27:37 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:27:37 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:27:45 dm900 enigma2[271]: action -> InfobarChannelSelection switchChannelDown Oct 03 09:27:45 dm900 enigma2[271]: reused converter! Oct 03 09:27:45 dm900 enigma2[271]: reused converter! Oct 03 09:27:45 dm900 enigma2[271]: reused converter! Oct 03 09:27:46 dm900 enigma2[271]: action -> ChannelSelectBaseActions selectServicePageDown Oct 03 09:27:46 dm900 enigma2[271]: unknown action ChannelSelectBaseActions/selectServicePageDown! typo in keymap? Oct 03 09:27:46 dm900 enigma2[271]: action -> WizardActions right Oct 03 09:27:46 dm900 enigma2[271]: unknown action WizardActions/right! typo in keymap? Oct 03 09:27:49 dm900 enigma2[271]: action -> OkCancelActions ok Oct 03 09:27:49 dm900 enigma2[271]: playing 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:EF11:421:1:C00000:0:0:0: Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAService] don't send the same capmt twice Oct 03 09:27:49 dm900 enigma2[271]: [eDBusInterface::Private::sendEvent 0 Oct 03 09:27:49 dm900 enigma2[271]: [TeleText] service stopped Oct 03 09:27:49 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 0, 0]) Oct 03 09:27:49 dm900 enigma2[271]: disable dvb subtitles Oct 03 09:27:49 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:27:49 dm900 enigma2[271]: decoder state: play, vpid=-1, apid=-1 Oct 03 09:27:49 dm900 enigma2[271]: DEMUX_STOP - pcr - ok Oct 03 09:27:49 dm900 kernel: stop PCR0 on dmx 0 pid:1ff Oct 03 09:27:49 dm900 kernel: stop VIDEO0 on dmx 0 pid:1ff Oct 03 09:27:49 dm900 enigma2[271]: DEMUX_STOP - video - ok Oct 03 09:27:49 dm900 enigma2[271]: VIDEO_STOP - ok Oct 03 09:27:49 dm900 kernel: VIDEO0: stop decode Oct 03 09:27:49 dm900 kernel: audio ch0: decoder stop Oct 03 09:27:49 dm900 enigma2[271]: AUDIO_STOP - ok Oct 03 09:27:49 dm900 enigma2[271]: DEMUX_STOP - audio - ok Oct 03 09:27:49 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:27:49 dm900 enigma2[271]: DEMUX_STOP - ttx - ok Oct 03 09:27:49 dm900 kernel: stop AUDIO0 on dmx 0 pid:203 Oct 03 09:27:49 dm900 kernel: audio ch0: xpt stop Oct 03 09:27:49 dm900 kernel: sync0 1fff no decoder left in sync handling so disable Oct 03 09:27:49 dm900 kernel: sync0: was last pcroffset0 user so free it Oct 03 09:27:49 dm900 enigma2[271]: [TeleText] service started Oct 03 09:27:49 dm900 enigma2[271]: not pauseable. Oct 03 09:27:49 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:27:49 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015669.58 Oct 03 09:27:49 dm900 enigma2[271]: allocate channel.. 0006:0085:00c00000 Oct 03 09:27:49 dm900 enigma2[271]: available channel.. 0421:0001:00c00000 Oct 03 09:27:49 dm900 enigma2[271]: [ 1] opening frontend Oct 03 09:27:49 dm900 kernel: bcm45208(1): enabled diseqc and wfe 1 Oct 03 09:27:49 dm900 kernel: bcm45208(1): in normal mode now Oct 03 09:27:49 dm900 kernel: bcm45208(1): rf input 1 used Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAService] new channel 0x19852c0! Oct 03 09:27:49 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:27:49 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:27:49 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 11914500H SR 27501000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:27:49 dm900 enigma2[271]: [ 1] tuning to 1314 mhz Oct 03 09:27:49 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:27:49 dm900 enigma2[271]: allocate Channel: res 0 Oct 03 09:27:49 dm900 enigma2[271]: allocate demux Oct 03 09:27:49 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAService] new service 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAService] add demux 1 to slot 0 service 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAService] use cached! Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAService] demux 1 mask 02 Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAServiceConn] send 77 bytes to '/tmp/camd.socket' Oct 03 09:27:49 dm900 enigma2[271]: 9f 80 32 49 03 00 81 21 00 2a 01 81 08 00 c0 00 00 00 06 [SEC-1] update oparm Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] locked 1 Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] set static current limiting Oct 03 09:27:49 dm900 kernel: bcm45208(1): voltage(1) is now 18V Oct 03 09:27:49 dm900 kernel: start PCR0 on dmx 1 pid:2ff index 13 Oct 03 09:27:49 dm900 kernel: start AUDIO0 on dmx 1 pid:302 index 11 Oct 03 09:27:49 dm900 kernel: audio ch0: xpt start Oct 03 09:27:49 dm900 kernel: sync0 is off so we enable it Oct 03 09:27:49 dm900 kernel: sync0: allocated pcroffset 0 Oct 03 09:27:49 dm900 kernel: sync0: demux-index 1 pcr pid 2ff Oct 03 09:27:49 dm900 kernel: sync0: using 13 as pcr pidchannel Oct 03 09:27:49 dm900 kernel: audio ch0: AC3 Oct 03 09:27:49 dm900 kernel: audio ch0: decoder start Oct 03 09:27:49 dm900 kernel: audio ch0 delay 18000 (200 ms) Oct 03 09:27:49 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:27:49 dm900 kernel: start VIDEO0 on dmx 1 pid:2ff index 13 Oct 03 09:27:49 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:27:49 dm900 enigma2[271]: 00 85 82 02 02 01 84 02 00 62 85 03 01 41 32 09 04 09 8c fa ba 09 04 09 c4 fb ba 09 04 09 af ff ba 06 00 20 00 00 1b 02 ff 00 00 06 03 02 00 00 06 03 03 00 00 06 03 04 00 00 Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCIInterfaces] addPMTHandler 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:27:49 dm900 enigma2[271]: 'SKY/00c00000' is in provider list of slot 0... so use it Oct 03 09:27:49 dm900 enigma2[271]: (1)CISlot 0, usecount now 1 Oct 03 09:27:49 dm900 enigma2[271]: setInputSource input1 to CI0 Oct 03 09:27:49 dm900 enigma2[271]: CI Slot 0 setSource(1) Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:27:49 dm900 enigma2[271]: have 1 video stream(s) (02ff), and 1 audio stream(s) (0302), and the pcr pid is 02ff, and the text pid is 0020 Oct 03 09:27:49 dm900 enigma2[271]: allocate demux Oct 03 09:27:49 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] setVoltage 18V Oct 03 09:27:49 dm900 enigma2[271]: decoder state: play, vpid=767, apid=770 Oct 03 09:27:49 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x2ff) - pcr - ok Oct 03 09:27:49 dm900 enigma2[271]: DEMUX_START - pcr - [SEC-1] sleep 10ms Oct 03 09:27:49 dm900 enigma2[271]: ok Oct 03 09:27:49 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x302) - audio - ok Oct 03 09:27:49 dm900 enigma2[271]: DEMUX_START - audio - ok Oct 03 09:27:49 dm900 enigma2[271]: AUDIO_SET_BYPASS(0) - ok Oct 03 09:27:49 dm900 enigma2[271]: AUDIO_PAUSE - ok Oct 03 09:27:49 dm900 enigma2[271]: AUDIO_PLAY - ok Oct 03 09:27:49 dm900 enigma2[271]: Video Device: /dev/dvb/adapter0/video0 Oct 03 09:27:49 dm900 enigma2[271]: demux device: /dev/dvb/adapter0/demux1 Oct 03 09:27:49 dm900 enigma2[271]: VIDEO_SET_STREAMTYPE 1 - ok Oct 03 09:27:49 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x2ff) - video - ok Oct 03 09:27:49 dm900 enigma2[271]: DEMUX_START - video - ok Oct 03 09:27:49 dm900 enigma2[271]: VIDEO_FREEZE - ok Oct 03 09:27:49 dm900 kernel: VIDEO0: start decode Oct 03 09:27:49 dm900 enigma2[271]: VIDEO_PLAY - ok Oct 03 09:27:49 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x20) - ttx - ok Oct 03 09:27:49 dm900 enigma2[271]: DEMUX_START - ttx - ok Oct 03 09:27:49 dm900 enigma2[271]: VIDEO_SLOWMOTION(0) - ok Oct 03 09:27:49 dm900 enigma2[271]: VIDEO_FAST_FORWARD(0) - ok Oct 03 09:27:49 dm900 enigma2[271]: VIDEO_CONTINUE - ok Oct 03 09:27:49 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:27:49 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:27:49 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015669.63 Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] setTone On Oct 03 09:27:49 dm900 enigma2[271]: not pauseable. Oct 03 09:27:49 dm900 enigma2[271]: [HbbTV].aitInvalidated Oct 03 09:27:49 dm900 kernel: bcm45208(1): 22 Khz(1) is now enabled Oct 03 09:27:49 dm900 enigma2[271]: I/ [InfoBar.__restoreState] :: Oct 03 09:27:49 dm900 enigma2[271]: create buffer for widget 1280 x 150 Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] sleep 10ms Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] update current switch params Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:27:49 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] unlock 1 Oct 03 09:27:49 dm900 enigma2[271]: [SEC-1] sleep 500ms Oct 03 09:27:49 dm900 enigma2[271]: nr_read 913 nr_write 1689 Oct 03 09:27:49 dm900 enigma2[271]: sum 2602 prev_sum 2492 Oct 03 09:27:49 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:27:49 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:27:49 dm900 enigma2[271]: [ 1] FE_GET_EVENT failed (Value too large for defined data type) Oct 03 09:27:49 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 1 F Oct 03 09:27:49 dm900 enigma2[271]: [ 1] fe event: status 1f, freq 1314500, inversion off, m_tuning 1 N Oct 03 09:27:49 dm900 enigma2[271]: OURSTATE: ok Oct 03 09:27:49 dm900 enigma2[271]: [eDVBCAService] channel 0x19852c0 running Oct 03 09:27:49 dm900 enigma2[271]: [eDVBLocalTimeHandler] channel 0x19852c0 running Oct 03 09:27:49 dm900 enigma2[271]: no version filtering Oct 03 09:27:49 dm900 enigma2[271]: 0014: 70 00 00 00 00 00 Oct 03 09:27:49 dm900 enigma2[271]: mask: fc 00 00 00 00 00 Oct 03 09:27:49 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:27:49 dm900 enigma2[271]: [EPGC] channel 0x19852c0 running Oct 03 09:27:49 dm900 enigma2[271]: [EPGC] next update in 57 min Oct 03 09:27:49 dm900 enigma2[271]: stop release channel timer Oct 03 09:27:49 dm900 enigma2[271]: no version filtering Oct 03 09:27:49 dm900 enigma2[271]: 0012: 4e 00 81 00 00 00 Oct 03 09:27:49 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:27:49 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:27:49 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:27:49 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015669.98 Oct 03 09:27:49 dm900 enigma2[271]: ok ... now we start!! Oct 03 09:27:49 dm900 enigma2[271]: no version filtering Oct 03 09:27:49 dm900 enigma2[271]: 0000: 00 00 00 00 00 00 Oct 03 09:27:49 dm900 enigma2[271]: mask: ff 00 00 00 00 00 Oct 03 09:27:49 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:27:50 dm900 enigma2[271]: + 1/1 TID 00 Oct 03 09:27:50 dm900 enigma2[271]: done! Oct 03 09:27:50 dm900 enigma2[271]: PATready Oct 03 09:27:50 dm900 enigma2[271]: use pmtpid 0062 for service_id 0081 Oct 03 09:27:50 dm900 enigma2[271]: no version filtering Oct 03 09:27:50 dm900 enigma2[271]: 0062: 02 00 81 00 00 00 Oct 03 09:27:50 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:27:50 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:27:50 dm900 enigma2[271]: doing version filtering Oct 03 09:27:50 dm900 enigma2[271]: 0000: 00 00 00 05 00 00 Oct 03 09:27:50 dm900 enigma2[271]: mask: ff 00 00 3f 00 00 Oct 03 09:27:50 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:27:50 dm900 enigma2[271]: + 1/1 TID 02 Oct 03 09:27:50 dm900 enigma2[271]: done! Oct 03 09:27:50 dm900 enigma2[271]: [eDVBCAService] don't send the same capmt twice Oct 03 09:27:50 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:27:50 dm900 enigma2[271]: eventNewProgramInfo 0 0 Oct 03 09:27:50 dm900 enigma2[271]: have 1 video stream(s) (02ff), and 3 audio stream(s) (0302, 0303, 0304), and the pcr pid is 02ff, and the text pid is 0020 Oct 03 09:27:50 dm900 enigma2[271]: decoder state: play, vpid=767, apid=770 Oct 03 09:27:50 dm900 enigma2[271]: [TeleText] serviceInfoChanged Oct 03 09:27:50 dm900 enigma2[271]: [TeleText] TXT PID 32 DEMUX 1 Oct 03 09:27:50 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 32, 1]) Oct 03 09:27:50 dm900 enigma2[271]: doing version filtering Oct 03 09:27:50 dm900 enigma2[271]: 0062: 02 00 81 21 00 00 Oct 03 09:27:50 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:27:50 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:27:50 dm900 enigma2[271]: [SEC-1] set dynamic current limiting Oct 03 09:27:50 dm900 enigma2[271]: sdt update done! Oct 03 09:27:50 dm900 enigma2[271]: -+ 1/2 TID 4e Oct 03 09:27:50 dm900 enigma2[271]: [eDVBLocalTimeHandler] Receiver time is 'Tue Oct 3 09:27:50 2017' Oct 03 09:27:49 dm900 enigma2[271]: [eDVBLocalTimeHandler] Transponder time is 'Tue Oct 3 09:27:49 2017' Oct 03 09:27:49 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff is -1 Oct 03 09:27:49 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Oct 03 09:27:49 dm900 enigma2[271]: [eDVBLocalTimeHandler] update RTC Oct 03 09:27:49 dm900 enigma2[271]: [eDVBLocalTimeHandler] time_difference is -1 Oct 03 09:27:49 dm900 enigma2[271]: [eDVBLocalTimeHandler] update Linux Time Oct 03 09:27:49 dm900 systemd[1]: Time has been changed Oct 03 09:27:50 dm900 systemd[1]: Time has been changed Oct 03 09:27:51 dm900 enigma2[271]: ++ 2/2 TID 4e Oct 03 09:27:51 dm900 enigma2[271]: done! Oct 03 09:27:51 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:27:51 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015671.48 Oct 03 09:27:51 dm900 enigma2[271]: doing version filtering Oct 03 09:27:51 dm900 enigma2[271]: 0012: 4e 00 81 2d 00 00 Oct 03 09:27:51 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:27:51 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:01 dm900 enigma2[271]: nr_read 913 nr_write 1770 Oct 03 09:28:01 dm900 enigma2[271]: sum 2683 prev_sum 2602 Oct 03 09:28:01 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:28:01 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:28:07 dm900 enigma2[271]: action -> InfobarChannelSelection zapDown Oct 03 09:28:07 dm900 enigma2[271]: playing 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] free slot 0 demux 1 for service 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] free service 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCIInterfaces] remove last pmt handler for service 1:0:19:81:6:85:C00000:0:0:0: send empty capmt Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCISlot] capmt version equal... increment old was 21! new version is 23 Oct 03 09:28:07 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:28:07 dm900 enigma2[271]: (3) slot 0 usecount is now 0 Oct 03 09:28:07 dm900 enigma2[271]: [eDBusInterface::Private::sendEvent 0 Oct 03 09:28:07 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:07 dm900 enigma2[271]: [TeleText] service stopped Oct 03 09:28:07 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 0, 0]) Oct 03 09:28:07 dm900 enigma2[271]: disable dvb subtitles Oct 03 09:28:07 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:07 dm900 enigma2[271]: decoder state: play, vpid=-1, apid=-1 Oct 03 09:28:07 dm900 enigma2[271]: DEMUX_STOP - pcr - ok Oct 03 09:28:07 dm900 kernel: stop PCR0 on dmx 1 pid:2ff Oct 03 09:28:07 dm900 kernel: stop VIDEO0 on dmx 1 pid:2ff Oct 03 09:28:07 dm900 kernel: bcm_mpeg_decoder_get_pts: 3 callbacks suppressed Oct 03 09:28:07 dm900 kernel: VIDEO0 use saved pts 0x40112228 (ret 0) Oct 03 09:28:07 dm900 kernel: VIDEO0: stop decode Oct 03 09:28:07 dm900 kernel: audio ch0: use saved pts 0x40111734 (0x1931aca80) (ret 1) Oct 03 09:28:07 dm900 kernel: audio ch0: decoder stop Oct 03 09:28:07 dm900 enigma2[271]: DEMUX_STOP - video - ok Oct 03 09:28:07 dm900 enigma2[271]: VIDEO_STOP - ok Oct 03 09:28:07 dm900 enigma2[271]: AUDIO_STOP - ok Oct 03 09:28:07 dm900 kernel: stop AUDIO0 on dmx 1 pid:302 Oct 03 09:28:07 dm900 kernel: audio ch0: xpt stop Oct 03 09:28:07 dm900 kernel: sync0 1fff no decoder left in sync handling so disable Oct 03 09:28:07 dm900 kernel: sync0: was last pcroffset0 user so free it Oct 03 09:28:07 dm900 enigma2[271]: DEMUX_STOP - audio - ok Oct 03 09:28:07 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:07 dm900 enigma2[271]: DEMUX_STOP - ttx - ok Oct 03 09:28:07 dm900 enigma2[271]: start release channel timer Oct 03 09:28:07 dm900 enigma2[271]: [TeleText] service started Oct 03 09:28:07 dm900 enigma2[271]: create buffer for widget 1280 x 150 Oct 03 09:28:07 dm900 enigma2[271]: not pauseable. Oct 03 09:28:07 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:07 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015687.6 Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] remove channel 0x19852c0 Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Oct 03 09:28:07 dm900 enigma2[271]: [eDVBLocalTimeHandler] remove channel 0x19852c0 Oct 03 09:28:07 dm900 enigma2[271]: [EPGC] remove channel 0x19852c0 Oct 03 09:28:07 dm900 enigma2[271]: [ 1] preClose Oct 03 09:28:07 dm900 enigma2[271]: allocate channel.. 000d:0085:00c00000 Oct 03 09:28:07 dm900 enigma2[271]: available channel.. 0421:0001:00c00000 Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] new channel 0x19852c0! Oct 03 09:28:07 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:28:07 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:28:07 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 11992500H SR 27500000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:28:07 dm900 enigma2[271]: [ 1] tuning to 1392 mhz Oct 03 09:28:07 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:28:07 dm900 enigma2[271]: allocate Channel: res 0 Oct 03 09:28:07 dm900 enigma2[271]: [SEC-1] update oparm Oct 03 09:28:07 dm900 enigma2[271]: allocate demux Oct 03 09:28:07 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:28:07 dm900 enigma2[271]: [SEC-1] locked 1 Oct 03 09:28:07 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:28:07 dm900 enigma2[271]: [SEC-1] set static current limiting Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] new service 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] add demux 1 to slot 0 service 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] use cached! Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] demux 1 mask 02 Oct 03 09:28:07 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:28:07 dm900 enigma2[271]: [SEC-1] update current switch params Oct 03 09:28:07 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:28:07 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:28:07 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAServiceConn] send 72 bytes to '/tmp/camd.socket' Oct 03 09:28:07 dm900 enigma2[271]: 9f 80 32 44 03 00 72 [SEC-1] unlock 1 Oct 03 09:28:07 dm900 enigma2[271]: 13 [SEC-1] sleep 500ms Oct 03 09:28:07 dm900 enigma2[271]: 00 2a 01 81 08 00 c0 00 00 00 0d 00 85 82 02 02 01 84 02 00 63 85 03 01 41 32 09 04 09 8c fa 01 09 04 09 c4 fb 01 09 04 09 af ff 01 06 00 20 00 00 1b 03 ff 00 00 06 04 02 00 00 06 04 03 00 00 Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCIInterfaces] addPMTHandler 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:07 dm900 enigma2[271]: 'SKY/00c00000' is in provider list of slot 0... so use it Oct 03 09:28:07 dm900 enigma2[271]: (1)CISlot 0, usecount now 1 Oct 03 09:28:07 dm900 enigma2[271]: setInputSource input1 to CI0 Oct 03 09:28:07 dm900 enigma2[271]: CI Slot 0 setSource(1) Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:07 dm900 enigma2[271]: have 1 video stream(s) (03ff), and 1 audio stream(s) (0402), and the pcr pid is 03ff, and the text pid is 0020 Oct 03 09:28:07 dm900 enigma2[271]: allocate demux Oct 03 09:28:07 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:07 dm900 enigma2[271]: decoder state: play, vpid=1023, apid=1026 Oct 03 09:28:07 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x3ff) - pcr - ok Oct 03 09:28:07 dm900 kernel: start PCR0 on dmx 1 pid:3ff index 13 Oct 03 09:28:07 dm900 kernel: start AUDIO0 on dmx 1 pid:402 index 11 Oct 03 09:28:07 dm900 kernel: audio ch0: xpt start Oct 03 09:28:07 dm900 kernel: sync0 is off so we enable it Oct 03 09:28:07 dm900 kernel: sync0: allocated pcroffset 0 Oct 03 09:28:07 dm900 kernel: sync0: demux-index 1 pcr pid 3ff Oct 03 09:28:07 dm900 kernel: sync0: using 13 as pcr pidchannel Oct 03 09:28:07 dm900 kernel: audio ch0: AC3 Oct 03 09:28:07 dm900 kernel: audio ch0: decoder start Oct 03 09:28:07 dm900 kernel: audio ch0 delay 18000 (200 ms) Oct 03 09:28:07 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:07 dm900 kernel: start VIDEO0 on dmx 1 pid:3ff index 13 Oct 03 09:28:07 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:07 dm900 enigma2[271]: DEMUX_START - pcr - ok Oct 03 09:28:07 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x402) - audio - ok Oct 03 09:28:07 dm900 enigma2[271]: DEMUX_START - audio - ok Oct 03 09:28:07 dm900 enigma2[271]: AUDIO_SET_BYPASS(0) - ok Oct 03 09:28:07 dm900 enigma2[271]: AUDIO_PAUSE - ok Oct 03 09:28:07 dm900 enigma2[271]: AUDIO_PLAY - ok Oct 03 09:28:07 dm900 enigma2[271]: Video Device: /dev/dvb/adapter0/video0 Oct 03 09:28:07 dm900 enigma2[271]: demux device: /dev/dvb/adapter0/demux1 Oct 03 09:28:07 dm900 enigma2[271]: VIDEO_SET_STREAMTYPE 1 - ok Oct 03 09:28:07 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x3ff) - video - ok Oct 03 09:28:07 dm900 enigma2[271]: DEMUX_START - video - ok Oct 03 09:28:07 dm900 enigma2[271]: VIDEO_FREEZE - ok Oct 03 09:28:07 dm900 kernel: VIDEO0: start decode Oct 03 09:28:07 dm900 enigma2[271]: VIDEO_PLAY - ok Oct 03 09:28:07 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x20) - ttx - ok Oct 03 09:28:07 dm900 enigma2[271]: DEMUX_START - ttx - ok Oct 03 09:28:07 dm900 enigma2[271]: VIDEO_SLOWMOTION(0) - ok Oct 03 09:28:07 dm900 enigma2[271]: VIDEO_FAST_FORWARD(0) - ok Oct 03 09:28:07 dm900 enigma2[271]: VIDEO_CONTINUE - ok Oct 03 09:28:07 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:07 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:07 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015687.66 Oct 03 09:28:07 dm900 enigma2[271]: not pauseable. Oct 03 09:28:07 dm900 enigma2[271]: [HbbTV].aitInvalidated Oct 03 09:28:07 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been deleted Oct 03 09:28:07 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1392500, inversion off, m_tuning 1 F Oct 03 09:28:07 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1392500, inversion off, m_tuning 1 N Oct 03 09:28:07 dm900 enigma2[271]: [ 1] fe event: status 1f, freq 1392500, inversion off, m_tuning 2 N Oct 03 09:28:07 dm900 enigma2[271]: OURSTATE: ok Oct 03 09:28:07 dm900 enigma2[271]: [eDVBCAService] channel 0x19852c0 running Oct 03 09:28:07 dm900 enigma2[271]: [eDVBLocalTimeHandler] channel 0x19852c0 running Oct 03 09:28:07 dm900 enigma2[271]: no version filtering Oct 03 09:28:07 dm900 enigma2[271]: 0014: 70 00 00 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mask: fc 00 00 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: [EPGC] channel 0x19852c0 running Oct 03 09:28:07 dm900 enigma2[271]: stop release channel timer Oct 03 09:28:07 dm900 enigma2[271]: no version filtering Oct 03 09:28:07 dm900 enigma2[271]: 0012: 4e 00 72 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: [EPGC] next update in 2 sec Oct 03 09:28:07 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:07 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015687.95 Oct 03 09:28:07 dm900 enigma2[271]: ok ... now we start!! Oct 03 09:28:07 dm900 enigma2[271]: no version filtering Oct 03 09:28:07 dm900 enigma2[271]: 0000: 00 00 00 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mask: ff 00 00 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: + 1/1 TID 00 Oct 03 09:28:07 dm900 enigma2[271]: done! Oct 03 09:28:07 dm900 enigma2[271]: PATready Oct 03 09:28:07 dm900 enigma2[271]: use pmtpid 0063 for service_id 0072 Oct 03 09:28:07 dm900 enigma2[271]: no version filtering Oct 03 09:28:07 dm900 enigma2[271]: 0063: 02 00 72 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:07 dm900 enigma2[271]: doing version filtering Oct 03 09:28:07 dm900 enigma2[271]: 0000: 00 00 00 1d 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mask: ff 00 00 3f 00 00 Oct 03 09:28:07 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:08 dm900 enigma2[271]: + 1/1 TID 02 Oct 03 09:28:08 dm900 enigma2[271]: done! Oct 03 09:28:08 dm900 enigma2[271]: [eDVBCAService] don't send the same capmt twice Oct 03 09:28:08 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:08 dm900 enigma2[271]: eventNewProgramInfo 0 0 Oct 03 09:28:08 dm900 enigma2[271]: have 1 video stream(s) (03ff), and 2 audio stream(s) (0402, 0403), and the pcr pid is 03ff, and the text pid is 0020 Oct 03 09:28:08 dm900 enigma2[271]: decoder state: play, vpid=1023, apid=1026 Oct 03 09:28:08 dm900 enigma2[271]: [TeleText] serviceInfoChanged Oct 03 09:28:08 dm900 enigma2[271]: [TeleText] TXT PID 32 DEMUX 1 Oct 03 09:28:08 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 32, 1]) Oct 03 09:28:08 dm900 enigma2[271]: doing version filtering Oct 03 09:28:08 dm900 enigma2[271]: 0063: 02 00 72 13 00 00 Oct 03 09:28:08 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:08 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:08 dm900 enigma2[271]: [SEC-1] set dynamic current limiting Oct 03 09:28:08 dm900 enigma2[271]: +- 1/2 TID 4e Oct 03 09:28:09 dm900 enigma2[271]: ++ 2/2 TID 4e Oct 03 09:28:09 dm900 enigma2[271]: done! Oct 03 09:28:09 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:09 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015689.26 Oct 03 09:28:09 dm900 enigma2[271]: doing version filtering Oct 03 09:28:09 dm900 enigma2[271]: 0012: 4e 00 72 01 00 00 Oct 03 09:28:09 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:09 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:09 dm900 enigma2[271]: [EPGC] start caching events(1507015689) Oct 03 09:28:08 dm900 systemd[1]: Time has been changed Oct 03 09:28:08 dm900 enigma2[271]: [eDVBLocalTimeHandler] Receiver time is 'Tue Oct 3 09:28:10 2017' Oct 03 09:28:08 dm900 enigma2[271]: [eDVBLocalTimeHandler] Transponder time is 'Tue Oct 3 09:28:08 2017' Oct 03 09:28:08 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff is -2 Oct 03 09:28:08 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Oct 03 09:28:08 dm900 enigma2[271]: [eDVBLocalTimeHandler] update RTC Oct 03 09:28:08 dm900 enigma2[271]: [eDVBLocalTimeHandler] time_difference is -2 Oct 03 09:28:08 dm900 enigma2[271]: [eDVBLocalTimeHandler] update Linux Time Oct 03 09:28:10 dm900 systemd[1]: Time has been changed Oct 03 09:28:10 dm900 enigma2[271]: sdt update done! Oct 03 09:28:13 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:13 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been created Oct 03 09:28:13 dm900 enigma2[271]: nr_read 913 nr_write 1861 Oct 03 09:28:13 dm900 enigma2[271]: sum 2774 prev_sum 2683 Oct 03 09:28:13 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:28:13 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:28:14 dm900 enigma2[271]: action -> InfobarChannelSelection zapDown Oct 03 09:28:14 dm900 enigma2[271]: playing 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] free slot 0 demux 1 for service 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] free service 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCIInterfaces] remove last pmt handler for service 1:0:19:72:D:85:C00000:0:0:0: send empty capmt Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCISlot] capmt version equal... increment old was 13! new version is 15 Oct 03 09:28:14 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:28:14 dm900 enigma2[271]: (3) slot 0 usecount is now 0 Oct 03 09:28:14 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:14 dm900 enigma2[271]: [eDBusInterface::Private::sendEvent 0 Oct 03 09:28:14 dm900 enigma2[271]: [TeleText] service stopped Oct 03 09:28:14 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 0, 0]) Oct 03 09:28:14 dm900 enigma2[271]: disable dvb subtitles Oct 03 09:28:14 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:14 dm900 kernel: stop PCR0 on dmx 1 pid:3ff Oct 03 09:28:14 dm900 kernel: stop VIDEO0 on dmx 1 pid:3ff Oct 03 09:28:14 dm900 kernel: VIDEO0 use saved pts 0x40112228 (ret 0) Oct 03 09:28:14 dm900 kernel: VIDEO0: stop decode Oct 03 09:28:14 dm900 kernel: audio ch0: use saved pts 0x40111734 (0x19a021f80) (ret 1) Oct 03 09:28:14 dm900 kernel: audio ch0: decoder stop Oct 03 09:28:14 dm900 enigma2[271]: decoder state: play, vpid=-1, apid=-1 Oct 03 09:28:14 dm900 enigma2[271]: DEMUX_STOP - pcr - ok Oct 03 09:28:14 dm900 enigma2[271]: DEMUX_STOP - video - ok Oct 03 09:28:14 dm900 enigma2[271]: VIDEO_STOP - ok Oct 03 09:28:14 dm900 enigma2[271]: AUDIO_STOP - ok Oct 03 09:28:14 dm900 enigma2[271]: DEMUX_STOP - audio - ok Oct 03 09:28:14 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:14 dm900 kernel: stop AUDIO0 on dmx 1 pid:402 Oct 03 09:28:14 dm900 kernel: audio ch0: xpt stop Oct 03 09:28:14 dm900 kernel: sync0 1fff no decoder left in sync handling so disable Oct 03 09:28:14 dm900 kernel: sync0: was last pcroffset0 user so free it Oct 03 09:28:14 dm900 enigma2[271]: DEMUX_STOP - ttx - ok Oct 03 09:28:14 dm900 enigma2[271]: start release channel timer Oct 03 09:28:14 dm900 enigma2[271]: [TeleText] service started Oct 03 09:28:14 dm900 enigma2[271]: create buffer for widget 1280 x 150 Oct 03 09:28:14 dm900 enigma2[271]: not pauseable. Oct 03 09:28:14 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:14 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015694.72 Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] remove channel 0x19852c0 Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Oct 03 09:28:14 dm900 enigma2[271]: [eDVBLocalTimeHandler] remove channel 0x19852c0 Oct 03 09:28:14 dm900 enigma2[271]: [EPGC] remove channel 0x19852c0 Oct 03 09:28:14 dm900 enigma2[271]: [EPGC] abort caching events !! Oct 03 09:28:14 dm900 enigma2[271]: [ 1] preClose Oct 03 09:28:14 dm900 enigma2[271]: allocate channel.. 000c:0085:00c00000 Oct 03 09:28:14 dm900 enigma2[271]: available channel.. 0421:0001:00c00000 Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] new channel 0x19852c0! Oct 03 09:28:14 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:28:14 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:28:14 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 12304750H SR 27501000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:28:14 dm900 enigma2[271]: [ 1] tuning to 1704 mhz Oct 03 09:28:14 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] update oparm Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] locked 1 Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] set static current limiting Oct 03 09:28:14 dm900 enigma2[271]: allocate Channel: res 0 Oct 03 09:28:14 dm900 enigma2[271]: allocate demux Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] update current switch params Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:28:14 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] unlock 1 Oct 03 09:28:14 dm900 enigma2[271]: [SEC-1] sleep 500ms Oct 03 09:28:14 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] new service 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] add demux 1 to slot 0 service 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] use cached! Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] demux 1 mask 02 Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAServiceConn] send 82 bytes to '/tmp/camd.socket' Oct 03 09:28:14 dm900 enigma2[271]: 9f 80 32 4e 03 00 69 0d 00 2a 01 81 08 00 c0 00 00 00 0c 00 85 82 02 02 01 84 02 00 60 85 03 01 41 32 09 04 09 c4 fb 0d 09 04 09 8c fa 0d 09 04 09 af ff 0d 1b 00 ff 00 00 06 01 02 00 00 06 01 03 00 00 06 01 04 00 00 06 01 05 00 00 06 01 06 00 00 Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCIInterfaces] addPMTHandler 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:14 dm900 enigma2[271]: 'SKY/00c00000' is in provider list of slot 0... so use it Oct 03 09:28:14 dm900 enigma2[271]: (1)CISlot 0, usecount now 1 Oct 03 09:28:14 dm900 enigma2[271]: setInputSource input1 to CI0 Oct 03 09:28:14 dm900 enigma2[271]: CI Slot 0 setSource(1) Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:14 dm900 enigma2[271]: have 1 video stream(s) (00ff), and 1 audio stream(s) (0102), and the pcr pid is 00ff, and the text pid is 0105 Oct 03 09:28:14 dm900 enigma2[271]: allocate demux Oct 03 09:28:14 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:14 dm900 enigma2[271]: decoder state: play, vpid=255, apid=258 Oct 03 09:28:14 dm900 kernel: start PCR0 on dmx 1 pid:ff index 13 Oct 03 09:28:14 dm900 enigma2[271]: DMX_SET_PES_FILTER(0xff) - pcr - ok Oct 03 09:28:14 dm900 enigma2[271]: DEMUX_START - pcr - ok Oct 03 09:28:14 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x102) - audio - ok Oct 03 09:28:14 dm900 enigma2[271]: DEMUX_START - audio - ok Oct 03 09:28:14 dm900 enigma2[271]: AUDIO_SET_BYPASS(0) - ok Oct 03 09:28:14 dm900 enigma2[271]: AUDIO_PAUSE - ok Oct 03 09:28:14 dm900 kernel: start AUDIO0 on dmx 1 pid:102 index 11 Oct 03 09:28:14 dm900 kernel: audio ch0: xpt start Oct 03 09:28:14 dm900 kernel: sync0 is off so we enable it Oct 03 09:28:14 dm900 kernel: sync0: allocated pcroffset 0 Oct 03 09:28:14 dm900 kernel: sync0: demux-index 1 pcr pid ff Oct 03 09:28:14 dm900 kernel: sync0: using 13 as pcr pidchannel Oct 03 09:28:14 dm900 kernel: audio ch0: AC3 Oct 03 09:28:14 dm900 kernel: audio ch0: decoder start Oct 03 09:28:14 dm900 kernel: audio ch0 delay 18000 (200 ms) Oct 03 09:28:14 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:14 dm900 kernel: start VIDEO0 on dmx 1 pid:ff index 13 Oct 03 09:28:14 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:14 dm900 enigma2[271]: AUDIO_PLAY - ok Oct 03 09:28:14 dm900 enigma2[271]: Video Device: /dev/dvb/adapter0/video0 Oct 03 09:28:14 dm900 enigma2[271]: demux device: /dev/dvb/adapter0/demux1 Oct 03 09:28:14 dm900 enigma2[271]: VIDEO_SET_STREAMTYPE 1 - ok Oct 03 09:28:14 dm900 enigma2[271]: DMX_SET_PES_FILTER(0xff) - video - ok Oct 03 09:28:14 dm900 enigma2[271]: DEMUX_START - video - ok Oct 03 09:28:14 dm900 enigma2[271]: VIDEO_FREEZE - ok Oct 03 09:28:14 dm900 enigma2[271]: VIDEO_PLAY - ok Oct 03 09:28:14 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x105) - ttx - ok Oct 03 09:28:14 dm900 kernel: VIDEO0: start decode Oct 03 09:28:14 dm900 enigma2[271]: DEMUX_START - ttx - ok Oct 03 09:28:14 dm900 enigma2[271]: VIDEO_SLOWMOTION(0) - ok Oct 03 09:28:14 dm900 enigma2[271]: VIDEO_FAST_FORWARD(0) - ok Oct 03 09:28:14 dm900 enigma2[271]: VIDEO_CONTINUE - ok Oct 03 09:28:14 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:14 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:14 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015694.79 Oct 03 09:28:14 dm900 enigma2[271]: not pauseable. Oct 03 09:28:14 dm900 enigma2[271]: [HbbTV].aitInvalidated Oct 03 09:28:14 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been deleted Oct 03 09:28:14 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1704750, inversion off, m_tuning 1 F Oct 03 09:28:14 dm900 enigma2[271]: [ 1] fe event: status 1f, freq 1704750, inversion off, m_tuning 1 N Oct 03 09:28:14 dm900 enigma2[271]: OURSTATE: ok Oct 03 09:28:14 dm900 enigma2[271]: [eDVBCAService] channel 0x19852c0 running Oct 03 09:28:14 dm900 enigma2[271]: [eDVBLocalTimeHandler] channel 0x19852c0 running Oct 03 09:28:14 dm900 enigma2[271]: no version filtering Oct 03 09:28:14 dm900 enigma2[271]: 0014: 70 00 00 00 00 00 Oct 03 09:28:14 dm900 enigma2[271]: mask: fc 00 00 00 00 00 Oct 03 09:28:14 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:14 dm900 enigma2[271]: [EPGC] channel 0x19852c0 running Oct 03 09:28:14 dm900 enigma2[271]: stop release channel timer Oct 03 09:28:14 dm900 enigma2[271]: no version filtering Oct 03 09:28:14 dm900 enigma2[271]: 0012: 4e 00 69 00 00 00 Oct 03 09:28:14 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:14 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:14 dm900 enigma2[271]: [EPGC] next update in 2 sec Oct 03 09:28:14 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:14 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015694.88 Oct 03 09:28:14 dm900 enigma2[271]: ok ... now we start!! Oct 03 09:28:14 dm900 enigma2[271]: no version filtering Oct 03 09:28:14 dm900 enigma2[271]: 0000: 00 00 00 00 00 00 Oct 03 09:28:14 dm900 enigma2[271]: mask: ff 00 00 00 00 00 Oct 03 09:28:14 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:15 dm900 enigma2[271]: + 1/1 TID 00 Oct 03 09:28:15 dm900 enigma2[271]: done! Oct 03 09:28:15 dm900 enigma2[271]: PATready Oct 03 09:28:15 dm900 enigma2[271]: use pmtpid 0060 for service_id 0069 Oct 03 09:28:15 dm900 enigma2[271]: no version filtering Oct 03 09:28:15 dm900 enigma2[271]: 0060: 02 00 69 00 00 00 Oct 03 09:28:15 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:15 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:15 dm900 enigma2[271]: doing version filtering Oct 03 09:28:15 dm900 enigma2[271]: 0000: 00 00 00 29 00 00 Oct 03 09:28:15 dm900 enigma2[271]: mask: ff 00 00 3f 00 00 Oct 03 09:28:15 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:15 dm900 enigma2[271]: +- 1/2 TID 4e Oct 03 09:28:15 dm900 enigma2[271]: + 1/1 TID 02 Oct 03 09:28:15 dm900 enigma2[271]: done! Oct 03 09:28:15 dm900 enigma2[271]: [eDVBCAService] don't send the same capmt twice Oct 03 09:28:15 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:15 dm900 enigma2[271]: eventNewProgramInfo 0 0 Oct 03 09:28:15 dm900 enigma2[271]: have 1 video stream(s) (00ff), and 3 audio stream(s) (0102, 0103, 0104), and the pcr pid is 00ff, and the text pid is 0105 Oct 03 09:28:15 dm900 enigma2[271]: decoder state: play, vpid=255, apid=258 Oct 03 09:28:15 dm900 enigma2[271]: [TeleText] serviceInfoChanged Oct 03 09:28:15 dm900 enigma2[271]: [TeleText] TXT PID 261 DEMUX 1 Oct 03 09:28:15 dm900 enigma2[271]: [TeleText] send array('B', [1, 1, 5, 1]) Oct 03 09:28:15 dm900 enigma2[271]: doing version filtering Oct 03 09:28:15 dm900 enigma2[271]: 0060: 02 00 69 0d 00 00 Oct 03 09:28:15 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:15 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:15 dm900 enigma2[271]: [EPGC] cleanup invalid data Oct 03 09:28:15 dm900 enigma2[271]: [SEC-1] set dynamic current limiting Oct 03 09:28:15 dm900 enigma2[271]: sdt update done! Oct 03 09:28:15 dm900 enigma2[271]: ++ 2/2 TID 4e Oct 03 09:28:15 dm900 enigma2[271]: done! Oct 03 09:28:15 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:15 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015695.91 Oct 03 09:28:15 dm900 enigma2[271]: doing version filtering Oct 03 09:28:15 dm900 enigma2[271]: 0012: 4e 00 69 29 00 00 Oct 03 09:28:15 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:15 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:16 dm900 enigma2[271]: [EPGC] start caching events(1507015696) Oct 03 09:28:15 dm900 enigma2[271]: [eDVBLocalTimeHandler] Receiver time is 'Tue Oct 3 09:28:16 2017' Oct 03 09:28:15 dm900 enigma2[271]: [eDVBLocalTimeHandler] Transponder time is 'Tue Oct 3 09:28:15 2017' Oct 03 09:28:15 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff is -1 Oct 03 09:28:15 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Oct 03 09:28:15 dm900 enigma2[271]: [eDVBLocalTimeHandler] update RTC Oct 03 09:28:15 dm900 enigma2[271]: [eDVBLocalTimeHandler] time_difference is -1 Oct 03 09:28:15 dm900 enigma2[271]: [eDVBLocalTimeHandler] update Linux Time Oct 03 09:28:15 dm900 systemd[1]: Time has been changed Oct 03 09:28:16 dm900 systemd[1]: Time has been changed Oct 03 09:28:19 dm900 enigma2[271]: 113972 ms latency per 1000 blits (8,774085 fps) Oct 03 09:28:23 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:23 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been created Oct 03 09:28:23 dm900 enigma2[271]: [EPGC] abort non avail schedule other reading Oct 03 09:28:23 dm900 enigma2[271]: [EPGC] abort non avail viasat reading Oct 03 09:28:23 dm900 enigma2[271]: [EPGC] nownext finished(1507015703) Oct 03 09:28:24 dm900 enigma2[271]: action -> InfobarChannelSelection switchChannelDown Oct 03 09:28:24 dm900 enigma2[271]: reused converter! Oct 03 09:28:24 dm900 enigma2[271]: reused converter! Oct 03 09:28:24 dm900 enigma2[271]: reused converter! Oct 03 09:28:25 dm900 enigma2[271]: action -> OkCancelActions ok Oct 03 09:28:25 dm900 enigma2[271]: playing 1:0:19:283D:3FB:1:C00000:0:0:0: Oct 03 09:28:25 dm900 enigma2[271]: [eDVBCAService] free slot 0 demux 1 for service 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:25 dm900 enigma2[271]: [eDVBCAService] free service 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:25 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:25 dm900 enigma2[271]: [eDVBCIInterfaces] remove last pmt handler for service 1:0:19:69:C:85:C00000:0:0:0: send empty capmt Oct 03 09:28:25 dm900 enigma2[271]: [eDVBCISlot] capmt version equal... increment old was 0d! new version is 0f Oct 03 09:28:25 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:28:25 dm900 enigma2[271]: (3) slot 0 usecount is now 0 Oct 03 09:28:25 dm900 enigma2[271]: [eDBusInterface::Private::sendEvent 0 Oct 03 09:28:25 dm900 enigma2[271]: [TeleText] service stopped Oct 03 09:28:25 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 0, 0]) Oct 03 09:28:25 dm900 enigma2[271]: disable dvb subtitles Oct 03 09:28:25 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:25 dm900 enigma2[271]: decoder state: play, vpid=-1, apid=-1 Oct 03 09:28:25 dm900 enigma2[271]: DEMUX_STOP - pcr - ok Oct 03 09:28:25 dm900 kernel: stop PCR0 on dmx 1 pid:ff Oct 03 09:28:25 dm900 kernel: stop VIDEO0 on dmx 1 pid:ff Oct 03 09:28:25 dm900 kernel: VIDEO0 use saved pts 0x40112228 (ret 0) Oct 03 09:28:25 dm900 kernel: VIDEO0: stop decode Oct 03 09:28:25 dm900 kernel: audio ch0: use saved pts 0x40111734 (0x198cc5500) (ret 1) Oct 03 09:28:25 dm900 kernel: audio ch0: decoder stop Oct 03 09:28:25 dm900 enigma2[271]: DEMUX_STOP - video - ok Oct 03 09:28:25 dm900 enigma2[271]: VIDEO_STOP - ok Oct 03 09:28:25 dm900 enigma2[271]: AUDIO_STOP - ok Oct 03 09:28:25 dm900 enigma2[271]: DEMUX_STOP - audio - ok Oct 03 09:28:25 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:25 dm900 kernel: stop AUDIO0 on dmx 1 pid:102 Oct 03 09:28:25 dm900 kernel: audio ch0: xpt stop Oct 03 09:28:25 dm900 kernel: sync0 1fff no decoder left in sync handling so disable Oct 03 09:28:25 dm900 kernel: sync0: was last pcroffset0 user so free it Oct 03 09:28:25 dm900 enigma2[271]: DEMUX_STOP - ttx - ok Oct 03 09:28:25 dm900 enigma2[271]: start release channel timer Oct 03 09:28:25 dm900 enigma2[271]: [TeleText] service started Oct 03 09:28:25 dm900 enigma2[271]: not pauseable. Oct 03 09:28:25 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:25 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015705.56 Oct 03 09:28:25 dm900 enigma2[271]: [eDVBCAService] remove channel 0x19852c0 Oct 03 09:28:25 dm900 enigma2[271]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Oct 03 09:28:25 dm900 enigma2[271]: [eDVBLocalTimeHandler] remove channel 0x19852c0 Oct 03 09:28:25 dm900 enigma2[271]: [EPGC] remove channel 0x19852c0 Oct 03 09:28:25 dm900 enigma2[271]: [EPGC] abort caching events !! Oct 03 09:28:25 dm900 kernel: bcm45208(1): voltage(1) is now 0V Oct 03 09:28:25 dm900 kernel: start PCR0 on dmx 1 pid:13ed index 13 Oct 03 09:28:25 dm900 kernel: start AUDIO0 on dmx 1 pid:13ee index 7 Oct 03 09:28:25 dm900 kernel: audio ch0: xpt start Oct 03 09:28:25 dm900 kernel: sync0 is off so we enable it Oct 03 09:28:25 dm900 kernel: sync0: allocated pcroffset 0 Oct 03 09:28:25 dm900 kernel: sync0: demux-index 1 pcr pid 13ed Oct 03 09:28:25 dm900 kernel: sync0: using 13 as pcr pidchannel Oct 03 09:28:25 dm900 kernel: audio ch0: MPEG Oct 03 09:28:25 dm900 kernel: audio ch0: streamtype set 0 Oct 03 09:28:25 dm900 kernel: audio ch0: streamtype_set: stop rave Oct 03 09:28:25 dm900 kernel: audio ch0: streamtype_set: restart rave Oct 03 09:28:25 dm900 kernel: audio ch0: decoder start Oct 03 09:28:25 dm900 kernel: audio ch0 delay 18000 (200 ms) Oct 03 09:28:25 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:25 dm900 kernel: start VIDEO0 on dmx 1 pid:13ed index 13 Oct 03 09:28:25 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:25 dm900 enigma2[271]: [ 1] preClose Oct 03 09:28:25 dm900 enigma2[271]: allocate channel.. 03fb:0001:00c00000 Oct 03 09:28:25 dm900 enigma2[271]: available channel.. 0421:0001:00c00000 Oct 03 09:28:25 dm900 enigma2[271]: [eDVBCAService] new channel 0x19852c0! Oct 03 09:28:25 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:28:25 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:28:25 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 11494000H SR 22001000 INV Auto FEC 2/3 orbpos 192 modulation 8PSK pilot On, rolloff 0.35 Oct 03 09:28:25 dm900 enigma2[271]: [ 1] tuning to 1744 mhz Oct 03 09:28:25 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:28:25 dm900 enigma2[271]: allocate Channel: res 0 Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] update oparm Oct 03 09:28:25 dm900 enigma2[271]: allocate demux[SEC-1] lock 0 Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] locked 0 Oct 03 09:28:25 dm900 enigma2[271]: [SEC-0] set static current limiting Oct 03 09:28:25 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:28:25 dm900 enigma2[271]: have 1 video stream(s) (13ed), and 1 audio stream(s) (13ee), and the pcr pid is 13ed, and the text pid is 13f0 Oct 03 09:28:25 dm900 enigma2[271]: allocate demux Oct 03 09:28:25 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] set tuner input A Oct 03 09:28:25 dm900 enigma2[271]: decoder state: play, vpid=5101, apid=5102 Oct 03 09:28:25 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x13ed) - pcr - ok Oct 03 09:28:25 dm900 enigma2[271]: DEMUX_START - pcr - ok Oct 03 09:28:25 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x13ee) - audio - ok Oct 03 09:28:25 dm900 enigma2[271]: DEMUX_START - audio - ok Oct 03 09:28:25 dm900 enigma2[271]: AUDIO_SET_BYPASS(1) - ok Oct 03 09:28:25 dm900 enigma2[271]: AUDIO_PAUSE - ok Oct 03 09:28:25 dm900 enigma2[271]: AUDIO_PLAY - ok Oct 03 09:28:25 dm900 enigma2[271]: Video Device: /dev/dvb/adapter0/video0 Oct 03 09:28:25 dm900 enigma2[271]: demux device: /dev/dvb/adapter0/demux1 Oct 03 09:28:25 dm900 enigma2[271]: VIDEO_SET_STREAMTYPE 1 - ok Oct 03 09:28:25 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x13ed) - video - ok Oct 03 09:28:25 dm900 enigma2[271]: DEMUX_START - video - ok Oct 03 09:28:25 dm900 enigma2[271]: VIDEO_FREEZE - ok Oct 03 09:28:25 dm900 kernel: VIDEO0: start decode Oct 03 09:28:25 dm900 enigma2[271]: VIDEO_PLAY - ok Oct 03 09:28:25 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x13f0) - ttx - ok Oct 03 09:28:25 dm900 enigma2[271]: DEMUX_START - ttx - ok Oct 03 09:28:25 dm900 enigma2[271]: VIDEO_SLOWMOTION(0) - ok Oct 03 09:28:25 dm900 enigma2[271]: VIDEO_FAST_FORWARD(0) - ok Oct 03 09:28:25 dm900 enigma2[271]: VIDEO_CONTINUE - ok Oct 03 09:28:25 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:25 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:25 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015705.58 Oct 03 09:28:25 dm900 enigma2[271]: not pauseable. Oct 03 09:28:25 dm900 enigma2[271]: [HbbTV].aitInvalidated Oct 03 09:28:25 dm900 enigma2[271]: I/ [InfoBar.__restoreState] :: Oct 03 09:28:25 dm900 enigma2[271]: create buffer for widget 1280 x 150 Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:28:25 dm900 enigma2[271]: [SEC-0] setVoltage 18V Oct 03 09:28:25 dm900 kernel: bcm45208(1): disabled diseqc and wfe 1 Oct 03 09:28:25 dm900 kernel: bcm45208(1): switched to rf_input 0 Oct 03 09:28:25 dm900 kernel: bcm45208(0): voltage(0) is now 18V Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] sleep 10ms Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] update current switch params Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:28:25 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] unlock 0 Oct 03 09:28:25 dm900 enigma2[271]: [SEC-1] sleep 500ms Oct 03 09:28:25 dm900 enigma2[271]: nr_read 913 nr_write 1958 Oct 03 09:28:25 dm900 enigma2[271]: sum 2871 prev_sum 2774 Oct 03 09:28:25 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:28:25 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:28:25 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been deleted Oct 03 09:28:25 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1744000, inversion off, m_tuning 1 F Oct 03 09:28:25 dm900 kernel: audio ch0: first pts 0x1bac49c38, stc 0x1bac48616, pcr valid Oct 03 09:28:25 dm900 kernel: audio ch0: lock Oct 03 09:28:25 dm900 kernel: audio ch0: sample rate changed isr (48000) Oct 03 09:28:25 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1744000, inversion off, m_tuning 1 N Oct 03 09:28:25 dm900 enigma2[271]: [ 1] fe event: status 1f, freq 1744000, inversion off, m_tuning 2 N Oct 03 09:28:25 dm900 enigma2[271]: OURSTATE: ok Oct 03 09:28:25 dm900 enigma2[271]: [eDVBCAService] channel 0x19852c0 running Oct 03 09:28:25 dm900 enigma2[271]: [eDVBLocalTimeHandler] channel 0x19852c0 running Oct 03 09:28:25 dm900 enigma2[271]: no version filtering Oct 03 09:28:25 dm900 enigma2[271]: 0014: 70 00 00 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: mask: fc 00 00 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: [EPGC] channel 0x19852c0 running Oct 03 09:28:25 dm900 enigma2[271]: stop release channel timer Oct 03 09:28:25 dm900 enigma2[271]: no version filtering Oct 03 09:28:25 dm900 enigma2[271]: 0012: 4e 28 3d 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: ok ... now we start!! Oct 03 09:28:25 dm900 enigma2[271]: no version filtering Oct 03 09:28:25 dm900 enigma2[271]: 0000: 00 00 00 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: mask: ff 00 00 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:25 dm900 enigma2[271]: [EPGC] next update in 2 sec Oct 03 09:28:26 dm900 enigma2[271]: [EPGC] cleanup invalid data Oct 03 09:28:26 dm900 enigma2[271]: + 1/1 TID 00 Oct 03 09:28:26 dm900 enigma2[271]: done! Oct 03 09:28:26 dm900 enigma2[271]: PATready Oct 03 09:28:26 dm900 enigma2[271]: use pmtpid 13ec for service_id 283d Oct 03 09:28:26 dm900 enigma2[271]: no version filtering Oct 03 09:28:26 dm900 enigma2[271]: 13ec: 02 28 3d 00 00 00 Oct 03 09:28:26 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:26 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:26 dm900 enigma2[271]: doing version filtering Oct 03 09:28:26 dm900 enigma2[271]: 0000: 00 00 00 05 00 00 Oct 03 09:28:26 dm900 enigma2[271]: mask: ff 00 00 3f 00 00 Oct 03 09:28:26 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:26 dm900 enigma2[271]: + 1/1 TID 02 Oct 03 09:28:26 dm900 enigma2[271]: done! Oct 03 09:28:26 dm900 enigma2[271]: [eDVBCAService] new service 1:0:19:283D:3FB:1:C00000:0:0:0: Oct 03 09:28:26 dm900 enigma2[271]: [eDVBCAService] add demux 1 to slot 0 service 1:0:19:283D:3FB:1:C00000:0:0:0: Oct 03 09:28:26 dm900 enigma2[271]: HBBTV broadcast data on pid 087b Oct 03 09:28:26 dm900 enigma2[271]: dvb subtitle PID 13f1 with wrong subtitling type (24)... force 0x10!! Oct 03 09:28:26 dm900 enigma2[271]: Rds PID 13f4 detected ? ! ? Oct 03 09:28:26 dm900 enigma2[271]: HBBTV broadcast data on pid 1434 Oct 03 09:28:26 dm900 enigma2[271]: [eDVBCAService] demux 1 mask 02 Oct 03 09:28:26 dm900 enigma2[271]: [eDVBCAServiceConn] send 89 bytes to '/tmp/camd.socket' Oct 03 09:28:26 dm900 enigma2[271]: 9f 80 32 55 03 28 3d 0b 00 18 01 81 08 00 c0 00 00 03 fb 00 01 82 02 02 01 84 02 13 ec 85 03 01 41 32 05 04 92 00 00 0c 04 98 00 00 0b 08 7b 00 00 1b 13 ed 00 00 03 13 ee 00 00 03 13 ef 00 00 06 13 f0 00 00 06 13 f1 00 00 06 13 f2 00 00 06 13 f4 00 00 0b 14 34 00 00 Oct 03 09:28:26 dm900 enigma2[271]: [eDVBCIInterfaces] addPMTHandler 1:0:19:283D:3FB:1:C00000:0:0:0: Oct 03 09:28:26 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:26 dm900 enigma2[271]: eventNewProgramInfo 0 0 Oct 03 09:28:26 dm900 enigma2[271]: start ait on pid 0492 Oct 03 09:28:26 dm900 enigma2[271]: no version filtering Oct 03 09:28:26 dm900 enigma2[271]: 0492: 74 00 00 00 00 00 Oct 03 09:28:26 dm900 enigma2[271]: mask: ff 00 00 00 00 00 Oct 03 09:28:26 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:26 dm900 enigma2[271]: have 1 video stream(s) (13ed), and 3 audio stream(s) (13ee, 13ef, 13f2), and the pcr pid is 13ed, and the text pid is 13f0 Oct 03 09:28:26 dm900 enigma2[271]: decoder state: play, vpid=5101, apid=5102 Oct 03 09:28:26 dm900 enigma2[271]: HBBTV broadcast data on pid 087b Oct 03 09:28:26 dm900 enigma2[271]: dvb subtitle PID 13f1 with wrong subtitling type (24)... force 0x10!! Oct 03 09:28:26 dm900 kernel: audio ch0: status ready isr Oct 03 09:28:26 dm900 enigma2[271]: Rds PID 13f4 detected ? ! ? Oct 03 09:28:26 dm900 enigma2[271]: HBBTV broadcast data on pid 1434 Oct 03 09:28:26 dm900 enigma2[271]: [TeleText] serviceInfoChanged Oct 03 09:28:26 dm900 enigma2[271]: [TeleText] TXT PID 5104 DEMUX 1 Oct 03 09:28:26 dm900 enigma2[271]: [TeleText] send array('B', [1, 19, 240, 1]) Oct 03 09:28:26 dm900 enigma2[271]: doing version filtering Oct 03 09:28:26 dm900 enigma2[271]: 13ec: 02 28 3d 0b 00 00 Oct 03 09:28:26 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:26 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:26 dm900 enigma2[271]: [SEC-0] set dynamic current limiting Oct 03 09:28:26 dm900 enigma2[271]: [eDVBLocalTimeHandler] Receiver time is 'Tue Oct 3 09:28:26 2017' Oct 03 09:28:26 dm900 enigma2[271]: [eDVBLocalTimeHandler] Transponder time is 'Tue Oct 3 09:28:26 2017' Oct 03 09:28:26 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff is 0 Oct 03 09:28:26 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Oct 03 09:28:26 dm900 enigma2[271]: [eDVBLocalTimeHandler] not changed Oct 03 09:28:26 dm900 kernel: VIDEO0: first pts 0x1bac68924 Oct 03 09:28:26 dm900 kernel: VIDEO0: framerate changed 6 Oct 03 09:28:26 dm900 kernel: VIDEO0: resolution changed (1280 720) Oct 03 09:28:26 dm900 kernel: set current avs auto aspect to 16:9 Oct 03 09:28:26 dm900 kernel: set sb to 3(1) Oct 03 09:28:26 dm900 enigma2[271]: VIDEO_GET_EVENT - ok Oct 03 09:28:26 dm900 enigma2[271]: VIDEO_GET_EVENT - ok Oct 03 09:28:26 dm900 enigma2[271]: VIDEO_GET_EVENT - ok Oct 03 09:28:26 dm900 enigma2[271]: unhandled DVBAPI Video Event 32 Oct 03 09:28:26 dm900 kernel: VIDEO0: enable tsm Oct 03 09:28:26 dm900 kernel: VIDEO0: progressive changed (1) Oct 03 09:28:26 dm900 enigma2[271]: VIDEO_GET_EVENT - ok Oct 03 09:28:26 dm900 enigma2[271]: [ 0] fe event: status 1f, freq 1082750, inversion off, m_tuning 0 N Oct 03 09:28:26 dm900 enigma2[271]: -+ 1/2 TID 4e Oct 03 09:28:26 dm900 enigma2[271]: sdt update done! Oct 03 09:28:27 dm900 enigma2[271]: ++ 2/2 TID 4e Oct 03 09:28:27 dm900 enigma2[271]: done! Oct 03 09:28:27 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:27 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015707.46 Oct 03 09:28:27 dm900 enigma2[271]: doing version filtering Oct 03 09:28:27 dm900 enigma2[271]: 0012: 4e 28 3d 2f 00 00 Oct 03 09:28:27 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:27 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:27 dm900 enigma2[271]: + 1/1 TID 74 Oct 03 09:28:27 dm900 enigma2[271]: done! Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] aitChanged pid=0x492 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:HBBTV-Start-DSMCC+Web Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: HBBTV-Start-DSMCC+Web (orgid=19, appid=1, urlBase=http://itv.ard.de/ardstart/, initalPath=index.html, controlCode=1, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.1 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 1 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.1' resolved to 'http://itv.ard.de/ardstart/index.html' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:EPG Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: EPG (orgid=19, appid=2, urlBase=http://itv.ard.de/ardepg/, initalPath=index.php, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.2 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 2 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.2' resolved to 'http://itv.ard.de/ardepg/index.php' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:ARD-Text-DSMCC Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: ARD-Text-DSMCC (orgid=19, appid=4, urlBase=http://itv2.ard.de/, initalPath=index.html, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.4 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 3 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.4' resolved to 'http://itv2.ard.de/index.html' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:Tagesschau Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: Tagesschau (orgid=19, appid=5, urlBase=http://www.tagesschau.de/, initalPath=hbbtv, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.5 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 4 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.5' resolved to 'http://www.tagesschau.de/hbbtv' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:Mediathek DasErste Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: Mediathek DasErste (orgid=19, appid=20, urlBase=http://hbbtv.daserste.de/, initalPath=index.php, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.14 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 5 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.14' resolved to 'http://hbbtv.daserste.de/index.php' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:HBBTV ARD-Mediathek Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: HBBTV ARD-Mediathek (orgid=19, appid=3, urlBase=http://hbbtv.ardmediathek.de/hbbtv-ard/, initalPath=mediathek/?devicegroup=hbbtv, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.3 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 6 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.3' resolved to 'http://hbbtv.ardmediathek.de/hbbtv-ard/mediathek/?devicegroup=hbbtv' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:BR-Mediathek Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: BR-Mediathek (orgid=19, appid=35, urlBase=http://www.br-online.de/mediathek-video-hbbtv/, initalPath=br.php, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.23 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 7 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.23' resolved to 'http://www.br-online.de/mediathek-video-hbbtv/br.php' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:HBBTV Tatort Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: HBBTV Tatort (orgid=19, appid=42, urlBase=http://hbbtv-tatort.daserste.de/, initalPath=index.php, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.2a Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 8 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.2a' resolved to 'http://hbbtv-tatort.daserste.de/index.php' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:HbbTV SR Mediathek Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: HbbTV SR Mediathek (orgid=19, appid=56, urlBase=http://hbbtv.sr-mediathek.de/, initalPath=index.php, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.38 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 9 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.38' resolved to 'http://hbbtv.sr-mediathek.de/index.php' Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] Profile 0000, Version 1.1.1 Oct 03 09:28:27 dm900 enigma2[271]: [eOipfApplication] deu:HBBTV Das Erste Check Eins Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] New Application: HBBTV Das Erste Check Eins (orgid=19, appid=66, urlBase=http://hbbtv.daserste.de/, initalPath=?checkeins=1, controlCode=2, visibility=2) Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] Resolving Application Locator: dvb://current.ait/13.42 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] We have 10 app(s) for service 1.1019.10301 Oct 03 09:28:27 dm900 enigma2[271]: [eHbbtv] 'dvb://current.ait/13.42' resolved to 'http://hbbtv.daserste.de/?checkeins=1' Oct 03 09:28:27 dm900 enigma2[271]: [HbbTV].readButtonApplicationReady, appid=13.1 Oct 03 09:28:27 dm900 enigma2[271]: [HbbTV].textApplicationReady, appid=13.4 Oct 03 09:28:27 dm900 enigma2[271]: doing version filtering Oct 03 09:28:27 dm900 enigma2[271]: 0492: 74 00 00 03 00 00 Oct 03 09:28:27 dm900 enigma2[271]: mask: ff 00 00 3f 00 00 Oct 03 09:28:27 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:27 dm900 enigma2[271]: [EPGC] start caching events(1507015707) Oct 03 09:28:32 dm900 enigma2[271]: action -> InfobarChannelSelection switchChannelUp Oct 03 09:28:32 dm900 enigma2[271]: reused converter! Oct 03 09:28:32 dm900 enigma2[271]: reused converter! Oct 03 09:28:32 dm900 enigma2[271]: reused converter! Oct 03 09:28:33 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:33 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been created Oct 03 09:28:33 dm900 enigma2[271]: action -> OkCancelActions ok Oct 03 09:28:33 dm900 enigma2[271]: playing 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:33 dm900 enigma2[271]: [eDVBCAService] free slot 0 demux 1 for service 1:0:19:283D:3FB:1:C00000:0:0:0: Oct 03 09:28:33 dm900 enigma2[271]: [eDVBCAService] free service 1:0:19:283D:3FB:1:C00000:0:0:0: Oct 03 09:28:33 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:33 dm900 enigma2[271]: [eDBusInterface::Private::sendEvent 0 Oct 03 09:28:33 dm900 enigma2[271]: [TeleText] service stopped Oct 03 09:28:33 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 0, 0]) Oct 03 09:28:33 dm900 enigma2[271]: disable dvb subtitles Oct 03 09:28:33 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:33 dm900 enigma2[271]: decoder state: play, vpid=-1, apid=-1 Oct 03 09:28:33 dm900 kernel: stop PCR0 on dmx 1 pid:13ed Oct 03 09:28:33 dm900 kernel: stop VIDEO0 on dmx 1 pid:13ed Oct 03 09:28:33 dm900 enigma2[271]: DEMUX_STOP - pcr - ok Oct 03 09:28:33 dm900 kernel: VIDEO0: stop decode Oct 03 09:28:33 dm900 enigma2[271]: DEMUX_STOP - video - ok Oct 03 09:28:33 dm900 enigma2[271]: VIDEO_STOP - ok Oct 03 09:28:33 dm900 kernel: audio ch0: decoder stop Oct 03 09:28:34 dm900 enigma2[271]: AUDIO_STOP - ok Oct 03 09:28:34 dm900 kernel: stop AUDIO0 on dmx 1 pid:13ee Oct 03 09:28:34 dm900 kernel: audio ch0: xpt stop Oct 03 09:28:34 dm900 kernel: sync0 1fff no decoder left in sync handling so disable Oct 03 09:28:34 dm900 kernel: sync0: was last pcroffset0 user so free it Oct 03 09:28:34 dm900 enigma2[271]: DEMUX_STOP - audio - ok Oct 03 09:28:34 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:34 dm900 enigma2[271]: DEMUX_STOP - ttx - ok Oct 03 09:28:34 dm900 enigma2[271]: start release channel timer Oct 03 09:28:34 dm900 enigma2[271]: [TeleText] service started Oct 03 09:28:34 dm900 enigma2[271]: not pauseable. Oct 03 09:28:34 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:34 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015714.02 Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] remove channel 0x19852c0 Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Oct 03 09:28:34 dm900 enigma2[271]: [eDVBLocalTimeHandler] remove channel 0x19852c0 Oct 03 09:28:34 dm900 enigma2[271]: [EPGC] remove channel 0x19852c0 Oct 03 09:28:34 dm900 enigma2[271]: [EPGC] abort caching events !! Oct 03 09:28:34 dm900 enigma2[271]: [ 1] preClose Oct 03 09:28:34 dm900 enigma2[271]: allocate channel.. 000c:0085:00c00000 Oct 03 09:28:34 dm900 enigma2[271]: available channel.. 0421:0001:00c00000 Oct 03 09:28:34 dm900 enigma2[271]: [ 2] opening frontend Oct 03 09:28:34 dm900 kernel: bcm45208(2): enabled diseqc and wfe 1 Oct 03 09:28:34 dm900 kernel: bcm45208(2): in normal mode now Oct 03 09:28:34 dm900 kernel: bcm45208(2): rf input 1 used Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] new channel 0x19852c0! Oct 03 09:28:34 dm900 enigma2[271]: [ 2] tune type 1 Oct 03 09:28:34 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:28:34 dm900 enigma2[271]: [ 2] prepare_sat System DVB-S2 Freq 12304750H SR 27501000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:28:34 dm900 enigma2[271]: [ 2] tuning to 1704 mhz Oct 03 09:28:34 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:28:34 dm900 enigma2[271]: allocate Channel: res 0 Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] update oparm Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] lock 0 Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] locked 2 Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] set static current limiting Oct 03 09:28:34 dm900 enigma2[271]: allocate demux Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] invalidate current switch params Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] setVoltage 18V Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] sleep 10ms Oct 03 09:28:34 dm900 kernel: bcm45208(2): voltage(1) is now 18V Oct 03 09:28:34 dm900 enigma2[271]: setInputSource input2 to A3 Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] new service 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] add demux 1 to slot 0 service 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] use cached! Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] demux 1 mask 02 Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAServiceConn] send 82 bytes to '/tmp/camd.socket' Oct 03 09:28:34 dm900 enigma2[271]: 9f 80 32 4e 03 00 69 0d 00 2a 01 81 08 00 c0 00 00 00 0c 00 85 82 02 02 01 84 02 00 60 85 03 02 41 33 09 04 09 c4 fb 0d 09 04 09 8c fa 0d 09 04 09 af ff 0d 1b 00 ff 00 00 06 01 02 00 00 06 01 03 00 00 06 01 04 00 00 06 01 05 00 00 06 01 06 00 00 Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCIInterfaces] addPMTHandler 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:34 dm900 enigma2[271]: 'SKY/00c00000' is in provider list of slot 0... so use it Oct 03 09:28:34 dm900 enigma2[271]: (1)CISlot 0, usecount now 1 Oct 03 09:28:34 dm900 enigma2[271]: setInputSource input2 to CI0 Oct 03 09:28:34 dm900 enigma2[271]: CI Slot 0 setSource(2) Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:34 dm900 enigma2[271]: have 1 video stream(s) (00ff), and 1 audio stream(s) (0102), and the pcr pid is 00ff, and the text pid is 0105 Oct 03 09:28:34 dm900 enigma2[271]: allocate demux Oct 03 09:28:34 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:34 dm900 kernel: start PCR0 on dmx 1 pid:ff index 13 Oct 03 09:28:34 dm900 kernel: start AUDIO0 on dmx 1 pid:102 index 11 Oct 03 09:28:34 dm900 kernel: audio ch0: xpt start Oct 03 09:28:34 dm900 kernel: sync0 is off so we enable it Oct 03 09:28:34 dm900 kernel: sync0: allocated pcroffset 0 Oct 03 09:28:34 dm900 kernel: sync0: demux-index 1 pcr pid ff Oct 03 09:28:34 dm900 kernel: sync0: using 13 as pcr pidchannel Oct 03 09:28:34 dm900 kernel: audio ch0: AC3 Oct 03 09:28:34 dm900 kernel: audio ch0: streamtype set 1 Oct 03 09:28:34 dm900 kernel: audio ch0: streamtype_set: stop rave Oct 03 09:28:34 dm900 kernel: audio ch0: decode Oct 03 09:28:34 dm900 kernel: audio ch0: streamtype_set: restart rave Oct 03 09:28:34 dm900 kernel: audio ch0: decoder start Oct 03 09:28:34 dm900 kernel: audio ch0 delay 18000 (200 ms) Oct 03 09:28:34 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:34 dm900 kernel: start VIDEO0 on dmx 1 pid:ff index 13 Oct 03 09:28:34 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] setTone On Oct 03 09:28:34 dm900 enigma2[271]: decoder state: play, vpid=255, apid=258 Oct 03 09:28:34 dm900 enigma2[271]: DMX_SET_PES_FILTER(0xff) - pcr - ok Oct 03 09:28:34 dm900 enigma2[271]: DEMUX_START - pcr - ok Oct 03 09:28:34 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x102) - audio - ok Oct 03 09:28:34 dm900 enigma2[271]: DEMUX_START - audio - ok Oct 03 09:28:34 dm900 enigma2[271]: AUDIO_SET_BYPASS(0) - ok Oct 03 09:28:34 dm900 enigma2[271]: AUDIO_PAUSE - ok Oct 03 09:28:34 dm900 enigma2[271]: AUDIO_PLAY - ok Oct 03 09:28:34 dm900 enigma2[271]: Video Device: /dev/dvb/adapter0/video0 Oct 03 09:28:34 dm900 enigma2[271]: demux device: /dev/dvb/adapter0/demux1 Oct 03 09:28:34 dm900 enigma2[271]: VIDEO_SET_STREAMTYPE 1 - ok Oct 03 09:28:34 dm900 enigma2[271]: DMX_SET_PES_FILTER(0xff) - video - ok Oct 03 09:28:34 dm900 enigma2[271]: DEMUX_START - video - ok Oct 03 09:28:34 dm900 enigma2[271]: VIDEO_FREEZE - ok Oct 03 09:28:34 dm900 kernel: VIDEO0: start decode Oct 03 09:28:34 dm900 enigma2[271]: VIDEO_PLAY - ok Oct 03 09:28:34 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x105) - ttx - ok Oct 03 09:28:34 dm900 enigma2[271]: DEMUX_START - ttx - ok Oct 03 09:28:34 dm900 enigma2[271]: VIDEO_SLOWMOTION(0) - ok Oct 03 09:28:34 dm900 enigma2[271]: VIDEO_FAST_FORWARD(0) - ok Oct 03 09:28:34 dm900 enigma2[271]: VIDEO_CONTINUE - ok Oct 03 09:28:34 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:34 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:34 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015714.08 Oct 03 09:28:34 dm900 enigma2[271]: not pauseable. Oct 03 09:28:34 dm900 enigma2[271]: [HbbTV].aitInvalidated Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] sleep 10ms Oct 03 09:28:34 dm900 kernel: bcm45208(2): 22 Khz(1) is now enabled Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] update current switch params Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] startTuneTimeout 5000 Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] setFrontend 1 Oct 03 09:28:34 dm900 enigma2[271]: [ 2] setting frontend Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] unlock 2 Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] sleep 500ms Oct 03 09:28:34 dm900 enigma2[271]: I/ [InfoBar.__restoreState] :: Oct 03 09:28:34 dm900 enigma2[271]: create buffer for widget 1280 x 150 Oct 03 09:28:34 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been deleted Oct 03 09:28:34 dm900 enigma2[271]: [ 2] fe event: status 0, freq 1704750, inversion off, m_tuning 1 F Oct 03 09:28:34 dm900 enigma2[271]: [ 2] fe event: status 1f, freq 1704750, inversion off, m_tuning 1 N Oct 03 09:28:34 dm900 enigma2[271]: OURSTATE: ok Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] channel 0x19852c0 running Oct 03 09:28:34 dm900 enigma2[271]: [eDVBLocalTimeHandler] channel 0x19852c0 running Oct 03 09:28:34 dm900 enigma2[271]: no version filtering Oct 03 09:28:34 dm900 enigma2[271]: 0014: 70 00 00 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mask: fc 00 00 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: [EPGC] channel 0x19852c0 running Oct 03 09:28:34 dm900 enigma2[271]: [EPGC] next update in 2 sec Oct 03 09:28:34 dm900 enigma2[271]: stop release channel timer Oct 03 09:28:34 dm900 enigma2[271]: no version filtering Oct 03 09:28:34 dm900 enigma2[271]: 0012: 4e 00 69 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:34 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015714.36 Oct 03 09:28:34 dm900 enigma2[271]: ok ... now we start!! Oct 03 09:28:34 dm900 enigma2[271]: no version filtering Oct 03 09:28:34 dm900 enigma2[271]: 0000: 00 00 00 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mask: ff 00 00 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: + 1/1 TID 00 Oct 03 09:28:34 dm900 enigma2[271]: done! Oct 03 09:28:34 dm900 enigma2[271]: PATready Oct 03 09:28:34 dm900 enigma2[271]: use pmtpid 0060 for service_id 0069 Oct 03 09:28:34 dm900 enigma2[271]: no version filtering Oct 03 09:28:34 dm900 enigma2[271]: 0060: 02 00 69 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:34 dm900 enigma2[271]: doing version filtering Oct 03 09:28:34 dm900 enigma2[271]: 0000: 00 00 00 29 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mask: ff 00 00 3f 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:34 dm900 enigma2[271]: [SEC-2] set dynamic current limiting Oct 03 09:28:34 dm900 enigma2[271]: + 1/1 TID 02 Oct 03 09:28:34 dm900 enigma2[271]: done! Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCAService] don't send the same capmt twice Oct 03 09:28:34 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:34 dm900 enigma2[271]: eventNewProgramInfo 0 0 Oct 03 09:28:34 dm900 enigma2[271]: have 1 video stream(s) (00ff), and 3 audio stream(s) (0102, 0103, 0104), and the pcr pid is 00ff, and the text pid is 0105 Oct 03 09:28:34 dm900 enigma2[271]: decoder state: play, vpid=255, apid=258 Oct 03 09:28:34 dm900 enigma2[271]: [TeleText] serviceInfoChanged Oct 03 09:28:34 dm900 enigma2[271]: [TeleText] TXT PID 261 DEMUX 1 Oct 03 09:28:34 dm900 enigma2[271]: [TeleText] send array('B', [1, 1, 5, 1]) Oct 03 09:28:34 dm900 enigma2[271]: doing version filtering Oct 03 09:28:34 dm900 enigma2[271]: 0060: 02 00 69 0d 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:34 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:34 dm900 enigma2[271]: [EPGC] cleanup invalid data Oct 03 09:28:34 dm900 enigma2[271]: -+ 1/2 TID 4e Oct 03 09:28:35 dm900 enigma2[271]: sdt update done! Oct 03 09:28:35 dm900 enigma2[271]: ++ 2/2 TID 4e Oct 03 09:28:35 dm900 enigma2[271]: done! Oct 03 09:28:35 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:35 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015715.84 Oct 03 09:28:35 dm900 enigma2[271]: doing version filtering Oct 03 09:28:35 dm900 enigma2[271]: 0012: 4e 00 69 29 00 00 Oct 03 09:28:35 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:35 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:36 dm900 enigma2[271]: [eDVBLocalTimeHandler] Receiver time is 'Tue Oct 3 09:28:36 2017' Oct 03 09:28:36 dm900 enigma2[271]: [eDVBLocalTimeHandler] Transponder time is 'Tue Oct 3 09:28:34 2017' Oct 03 09:28:36 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff is -2 Oct 03 09:28:36 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Oct 03 09:28:36 dm900 enigma2[271]: [eDVBLocalTimeHandler] update RTC Oct 03 09:28:36 dm900 enigma2[271]: [eDVBLocalTimeHandler] time_difference is -2 Oct 03 09:28:36 dm900 enigma2[271]: [eDVBLocalTimeHandler] update Linux Time Oct 03 09:28:34 dm900 systemd[1]: Time has been changed Oct 03 09:28:36 dm900 systemd[1]: Time has been changed Oct 03 09:28:36 dm900 enigma2[271]: [EPGC] start caching events(1507015716) Oct 03 09:28:37 dm900 enigma2[271]: [ 1] prepareClose running loop Oct 03 09:28:37 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:28:37 dm900 enigma2[271]: [SEC-1] locked 0 Oct 03 09:28:37 dm900 enigma2[271]: [SEC-1] delayed close frontend Oct 03 09:28:37 dm900 enigma2[271]: [SEC-1] unlock 0 Oct 03 09:28:37 dm900 enigma2[271]: [ 1] close frontend Oct 03 09:28:37 dm900 enigma2[271]: [SEC-1] setTone Off Oct 03 09:28:37 dm900 kernel: bcm45208(1): 22 Khz(0) is now disabled Oct 03 09:28:37 dm900 kernel: bcm45208(1): sleeping now Oct 03 09:28:37 dm900 enigma2[271]: nr_read 913 nr_write 2053 Oct 03 09:28:37 dm900 enigma2[271]: sum 2966 prev_sum 2871 Oct 03 09:28:37 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:28:37 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:28:43 dm900 enigma2[271]: [EPGC] abort non avail schedule other reading Oct 03 09:28:43 dm900 enigma2[271]: [EPGC] abort non avail viasat reading Oct 03 09:28:43 dm900 enigma2[271]: [EPGC] nownext finished(1507015723) Oct 03 09:28:43 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:43 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been created Oct 03 09:28:45 dm900 enigma2[271]: [EPGC] schedule finished(1507015725) Oct 03 09:28:45 dm900 enigma2[271]: [EPGC] stop caching events(1507015725) Oct 03 09:28:45 dm900 enigma2[271]: [EPGC] next update in 60 min Oct 03 09:28:45 dm900 enigma2[271]: [EPGC] cleanup invalid data Oct 03 09:28:48 dm900 enigma2[271]: action -> InfobarChannelSelection zapUp Oct 03 09:28:48 dm900 enigma2[271]: playing 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] free slot 0 demux 1 for service 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] free service 1:0:19:69:C:85:C00000:0:0:0: Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCIInterfaces] remove last pmt handler for service 1:0:19:69:C:85:C00000:0:0:0: send empty capmt Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCISlot] capmt version equal... increment old was 0d! new version is 0f Oct 03 09:28:48 dm900 enigma2[271]: setInputSource input2 to A3 Oct 03 09:28:48 dm900 enigma2[271]: (3) slot 0 usecount is now 0 Oct 03 09:28:48 dm900 enigma2[271]: [eDBusInterface::Private::sendEvent 0 Oct 03 09:28:48 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:48 dm900 enigma2[271]: [TeleText] service stopped Oct 03 09:28:48 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 0, 0]) Oct 03 09:28:48 dm900 enigma2[271]: disable dvb subtitles Oct 03 09:28:48 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:48 dm900 kernel: stop PCR0 on dmx 1 pid:ff Oct 03 09:28:48 dm900 kernel: stop VIDEO0 on dmx 1 pid:ff Oct 03 09:28:48 dm900 kernel: VIDEO0 use saved pts 0x1bacf4c1c (ret 0) Oct 03 09:28:48 dm900 enigma2[271]: decoder state: play, vpid=-1, apid=-1 Oct 03 09:28:48 dm900 enigma2[271]: DEMUX_STOP - pcr - ok Oct 03 09:28:48 dm900 enigma2[271]: DEMUX_STOP - video - ok Oct 03 09:28:48 dm900 enigma2[271]: VIDEO_STOP - ok Oct 03 09:28:48 dm900 kernel: VIDEO0: stop decode Oct 03 09:28:48 dm900 kernel: audio ch0: use saved pts 0x1bacf4188 (0x195669000) (ret 1) Oct 03 09:28:48 dm900 kernel: audio ch0: decoder stop Oct 03 09:28:48 dm900 enigma2[271]: AUDIO_STOP - ok Oct 03 09:28:48 dm900 enigma2[271]: DEMUX_STOP - audio - ok Oct 03 09:28:48 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:48 dm900 kernel: stop AUDIO0 on dmx 1 pid:102 Oct 03 09:28:48 dm900 kernel: audio ch0: xpt stop Oct 03 09:28:48 dm900 kernel: sync0 1fff no decoder left in sync handling so disable Oct 03 09:28:48 dm900 kernel: sync0: was last pcroffset0 user so free it Oct 03 09:28:48 dm900 enigma2[271]: DEMUX_STOP - ttx - ok Oct 03 09:28:48 dm900 enigma2[271]: start release channel timer Oct 03 09:28:48 dm900 enigma2[271]: [TeleText] service started Oct 03 09:28:48 dm900 enigma2[271]: create buffer for widget 1280 x 150 Oct 03 09:28:48 dm900 enigma2[271]: not pauseable. Oct 03 09:28:48 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:48 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015728.82 Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] remove channel 0x19852c0 Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Oct 03 09:28:48 dm900 enigma2[271]: [eDVBLocalTimeHandler] remove channel 0x19852c0 Oct 03 09:28:48 dm900 enigma2[271]: [EPGC] remove channel 0x19852c0 Oct 03 09:28:48 dm900 enigma2[271]: [ 2] preClose Oct 03 09:28:48 dm900 enigma2[271]: allocate channel.. 000d:0085:00c00000 Oct 03 09:28:48 dm900 enigma2[271]: available channel.. 0421:0001:00c00000 Oct 03 09:28:48 dm900 enigma2[271]: [ 1] opening frontend Oct 03 09:28:48 dm900 kernel: bcm45208(1): in normal mode now Oct 03 09:28:48 dm900 kernel: bcm45208(1): rf input 0 used Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] new channel 0x1a01bc0! Oct 03 09:28:48 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:28:48 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:28:48 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 11992500H SR 27500000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:28:48 dm900 enigma2[271]: [ 1] tuning to 1392 mhz Oct 03 09:28:48 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] update oparm Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] locked 1 Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] set static current limiting Oct 03 09:28:48 dm900 enigma2[271]: allocate Channel: res 0 Oct 03 09:28:48 dm900 enigma2[271]: allocate demux Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] set tuner input B Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] setVoltage 18V Oct 03 09:28:48 dm900 kernel: bcm45208(1): switched to rf_input 1 Oct 03 09:28:48 dm900 kernel: bcm45208(1): voltage(1) is now 18V Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] sleep 10ms Oct 03 09:28:48 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] new service 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] add demux 1 to slot 0 service 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] use cached! Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAService] demux 1 mask 02 Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCAServiceConn] send 72 bytes to '/tmp/camd.socket' Oct 03 09:28:48 dm900 enigma2[271]: 9f 80 32 44 03 00 72 13 00 2a 01 81 08 00 c0 00 00 00 0d 00 85 82 02 02 01 84 02 00 63 85 03 01 41 32 09 04 09 8c fa 01 09 04 09 c4 fb 01 09 04 09 af ff 01 06 00 20 00 00 1b 03 ff 00 00 06 04 02 00 00 06 04 03 00 00 Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCIInterfaces] addPMTHandler 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:28:48 dm900 enigma2[271]: 'SKY/00c00000' is in provider list of slot 0... so use it Oct 03 09:28:48 dm900 enigma2[271]: (1)CISlot 0, usecount now 1 Oct 03 09:28:48 dm900 enigma2[271]: setInputSource input1 to CI0 Oct 03 09:28:48 dm900 enigma2[271]: CI Slot 0 setSource(1) Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:48 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:48 dm900 enigma2[271]: have 1 video stream(s) (03ff), and 1 audio stream(s) (0402)[SEC-1] setTone On Oct 03 09:28:48 dm900 enigma2[271]: , and the pcr pid is 03ff, and the text pid is 0020 Oct 03 09:28:48 dm900 enigma2[271]: allocate demux Oct 03 09:28:48 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] sleep 10ms Oct 03 09:28:48 dm900 kernel: bcm45208(1): 22 Khz(1) is now enabled Oct 03 09:28:48 dm900 enigma2[271]: decoder state: play, vpid=1023, apid=1026 Oct 03 09:28:48 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x3ff) - pcr - ok Oct 03 09:28:48 dm900 enigma2[271]: DEMUX_START - pcr - ok Oct 03 09:28:48 dm900 kernel: start PCR0 on dmx 1 pid:3ff index 13 Oct 03 09:28:48 dm900 kernel: start AUDIO0 on dmx 1 pid:402 index 11 Oct 03 09:28:48 dm900 kernel: audio ch0: xpt start Oct 03 09:28:48 dm900 kernel: sync0 is off so we enable it Oct 03 09:28:48 dm900 kernel: sync0: allocated pcroffset 0 Oct 03 09:28:48 dm900 kernel: sync0: demux-index 1 pcr pid 3ff Oct 03 09:28:48 dm900 kernel: sync0: using 13 as pcr pidchannel Oct 03 09:28:48 dm900 kernel: audio ch0: AC3 Oct 03 09:28:48 dm900 kernel: audio ch0: decoder start Oct 03 09:28:48 dm900 kernel: audio ch0 delay 18000 (200 ms) Oct 03 09:28:48 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:48 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x402) - audio - ok Oct 03 09:28:48 dm900 enigma2[271]: DEMUX_START - audio - ok Oct 03 09:28:48 dm900 enigma2[271]: AUDIO_SET_BYPASS(0) - ok Oct 03 09:28:48 dm900 enigma2[271]: AUDIO_PAUSE - ok Oct 03 09:28:48 dm900 enigma2[271]: AUDIO_PLAY - ok Oct 03 09:28:48 dm900 enigma2[271]: Video Device: /dev/dvb/adapter0/video0 Oct 03 09:28:48 dm900 enigma2[271]: demux device: /dev/dvb/adapter0/demux1 Oct 03 09:28:48 dm900 kernel: start VIDEO0 on dmx 1 pid:3ff index 13 Oct 03 09:28:48 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:28:48 dm900 enigma2[271]: VIDEO_SET_STREAMTYPE 1 - ok Oct 03 09:28:48 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x3ff) - video - ok Oct 03 09:28:48 dm900 enigma2[271]: DEMUX_START - video - ok Oct 03 09:28:48 dm900 enigma2[271]: VIDEO_FREEZE - ok Oct 03 09:28:48 dm900 enigma2[271]: VIDEO_PLAY - [SEC-1] update current switch params Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:28:48 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] unlock 1 Oct 03 09:28:48 dm900 enigma2[271]: [SEC-1] sleep 500ms Oct 03 09:28:48 dm900 enigma2[271]: ok Oct 03 09:28:48 dm900 kernel: VIDEO0: start decode Oct 03 09:28:48 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x20) - ttx - ok Oct 03 09:28:48 dm900 enigma2[271]: DEMUX_START - ttx - ok Oct 03 09:28:48 dm900 enigma2[271]: VIDEO_SLOWMOTION(0) - ok Oct 03 09:28:48 dm900 enigma2[271]: VIDEO_FAST_FORWARD(0) - ok Oct 03 09:28:48 dm900 enigma2[271]: VIDEO_CONTINUE - ok Oct 03 09:28:48 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:28:48 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:48 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015728.94 Oct 03 09:28:48 dm900 enigma2[271]: not pauseable. Oct 03 09:28:48 dm900 enigma2[271]: [HbbTV].aitInvalidated Oct 03 09:28:48 dm900 enigma2[271]: It's now Di 03 Okt 2017 09:28:48 CEST Oct 03 09:28:48 dm900 enigma2[271]: [timer.py] next activation: 1507015828 (in 99008 ms) Oct 03 09:28:48 dm900 enigma2[271]: It's now Di 03 Okt 2017 09:28:48 CEST Oct 03 09:28:48 dm900 enigma2[271]: [timer.py] next activation: 1507015828 (in 99006 ms) Oct 03 09:28:48 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been deleted Oct 03 09:28:49 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1392500, inversion off, m_tuning 1 F Oct 03 09:28:49 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1392500, inversion off, m_tuning 1 N Oct 03 09:28:49 dm900 enigma2[271]: [ 1] fe event: status 1f, freq 1392500, inversion off, m_tuning 2 N Oct 03 09:28:49 dm900 enigma2[271]: OURSTATE: ok Oct 03 09:28:49 dm900 enigma2[271]: [eDVBCAService] channel 0x1a01bc0 running Oct 03 09:28:49 dm900 enigma2[271]: [eDVBLocalTimeHandler] channel 0x1a01bc0 running Oct 03 09:28:49 dm900 enigma2[271]: no version filtering Oct 03 09:28:49 dm900 enigma2[271]: 0014: 70 00 00 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mask: fc 00 00 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: [EPGC] channel 0x1a01bc0 running Oct 03 09:28:49 dm900 enigma2[271]: [EPGC] next update in 2 sec Oct 03 09:28:49 dm900 enigma2[271]: stop release channel timer Oct 03 09:28:49 dm900 enigma2[271]: no version filtering Oct 03 09:28:49 dm900 enigma2[271]: 0012: 4e 00 72 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:49 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015729.18 Oct 03 09:28:49 dm900 enigma2[271]: ok ... now we start!! Oct 03 09:28:49 dm900 enigma2[271]: no version filtering Oct 03 09:28:49 dm900 enigma2[271]: 0000: 00 00 00 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mask: ff 00 00 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: + 1/1 TID 00 Oct 03 09:28:49 dm900 enigma2[271]: done! Oct 03 09:28:49 dm900 enigma2[271]: PATready Oct 03 09:28:49 dm900 enigma2[271]: use pmtpid 0063 for service_id 0072 Oct 03 09:28:49 dm900 enigma2[271]: no version filtering Oct 03 09:28:49 dm900 enigma2[271]: 0063: 02 00 72 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mask: ff ff ff 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mode: 00 00 00 00 00 00 Oct 03 09:28:49 dm900 enigma2[271]: doing version filtering Oct 03 09:28:49 dm900 enigma2[271]: 0000: 00 00 00 1d 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mask: ff 00 00 3f 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:47 dm900 enigma2[271]: [eDVBLocalTimeHandler] Receiver time is 'Tue Oct 3 09:28:49 2017' Oct 03 09:28:47 dm900 enigma2[271]: [eDVBLocalTimeHandler] Transponder time is 'Tue Oct 3 09:28:47 2017' Oct 03 09:28:47 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff is -2 Oct 03 09:28:47 dm900 enigma2[271]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Oct 03 09:28:47 dm900 enigma2[271]: [eDVBLocalTimeHandler] update RTC Oct 03 09:28:47 dm900 enigma2[271]: [eDVBLocalTimeHandler] time_difference is -2 Oct 03 09:28:47 dm900 enigma2[271]: [eDVBLocalTimeHandler] update Linux Time Oct 03 09:28:47 dm900 systemd[1]: Time has been changed Oct 03 09:28:49 dm900 systemd[1]: Time has been changed Oct 03 09:28:49 dm900 enigma2[271]: + 1/1 TID 02 Oct 03 09:28:49 dm900 enigma2[271]: done! Oct 03 09:28:49 dm900 enigma2[271]: [eDVBCAService] don't send the same capmt twice Oct 03 09:28:49 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:28:49 dm900 enigma2[271]: eventNewProgramInfo 0 0 Oct 03 09:28:49 dm900 enigma2[271]: have 1 video stream(s) (03ff), and 2 audio stream(s) (0402, 0403), and the pcr pid is 03ff, and the text pid is 0020 Oct 03 09:28:49 dm900 enigma2[271]: decoder state: play, vpid=1023, apid=1026 Oct 03 09:28:49 dm900 enigma2[271]: [TeleText] serviceInfoChanged Oct 03 09:28:49 dm900 enigma2[271]: [TeleText] TXT PID 32 DEMUX 1 Oct 03 09:28:49 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 32, 1]) Oct 03 09:28:49 dm900 enigma2[271]: doing version filtering Oct 03 09:28:49 dm900 enigma2[271]: 0063: 02 00 72 13 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:49 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:49 dm900 enigma2[271]: [SEC-1] set dynamic current limiting Oct 03 09:28:49 dm900 enigma2[271]: nr_read 913 nr_write 2147 Oct 03 09:28:49 dm900 enigma2[271]: sum 3060 prev_sum 2966 Oct 03 09:28:49 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:28:49 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:28:50 dm900 enigma2[271]: +- 1/2 TID 4e Oct 03 09:28:50 dm900 enigma2[271]: sdt update done! Oct 03 09:28:51 dm900 enigma2[271]: ++ 2/2 TID 4e Oct 03 09:28:51 dm900 enigma2[271]: done! Oct 03 09:28:51 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:28:51 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015731.02 Oct 03 09:28:51 dm900 enigma2[271]: doing version filtering Oct 03 09:28:51 dm900 enigma2[271]: 0012: 4e 00 72 01 00 00 Oct 03 09:28:51 dm900 enigma2[271]: mask: ff ff ff 3f 00 00 Oct 03 09:28:51 dm900 enigma2[271]: mode: 00 00 00 3e 00 00 Oct 03 09:28:51 dm900 enigma2[271]: [EPGC] start caching events(1507015731) Oct 03 09:28:51 dm900 enigma2[271]: [ 2] prepareClose running loop Oct 03 09:28:51 dm900 enigma2[271]: [SEC-2] lock 0 Oct 03 09:28:51 dm900 enigma2[271]: [SEC-2] locked 2 Oct 03 09:28:51 dm900 enigma2[271]: [SEC-2] setTone Off Oct 03 09:28:51 dm900 kernel: bcm45208(2): 22 Khz(1) is now disabled Oct 03 09:28:51 dm900 enigma2[271]: [SEC-2] sleep 25ms Oct 03 09:28:51 dm900 enigma2[271]: [SEC-2] sendDiseqc: e01002 (DiSEqC peripherial power off) Oct 03 09:28:51 dm900 enigma2[271]: [SEC-2] sleep 150ms Oct 03 09:28:52 dm900 enigma2[271]: [SEC-2] delayed close frontend Oct 03 09:28:52 dm900 enigma2[271]: [SEC-2] unlock 2 Oct 03 09:28:52 dm900 enigma2[271]: [ 2] close frontend Oct 03 09:28:52 dm900 enigma2[271]: [SEC-2] setVoltage 0V Oct 03 09:28:52 dm900 kernel: bcm45208(2): voltage(1) is now 0V Oct 03 09:28:52 dm900 kernel: bcm45208(2): sleeping now Oct 03 09:28:52 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1392500, inversion off, m_tuning 2 N Oct 03 09:28:52 dm900 enigma2[271]: FE_TIMEDOUT! ..abort Oct 03 09:28:52 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:52 dm900 enigma2[271]: [ 1] lost lock Oct 03 09:28:52 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:52 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:52 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:53 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:28:53 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been created Oct 03 09:28:53 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:54 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:54 dm900 enigma2[271]: [ 1] lost lock Oct 03 09:28:55 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:56 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:58 dm900 enigma2[271]: [EPGC] abort non avail schedule other reading Oct 03 09:28:58 dm900 enigma2[271]: [EPGC] abort non avail viasat reading Oct 03 09:28:58 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:28:59 dm900 enigma2[271]: [ 1] stateLostLock Oct 03 09:28:59 dm900 enigma2[271]: OURSTATE: lost lock, trying to retune Oct 03 09:28:59 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:28:59 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:28:59 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 11992500H SR 27500000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:28:59 dm900 enigma2[271]: [ 1] tuning to 1392 mhz Oct 03 09:28:59 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] update oparm Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] locked 1 Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] set static current limiting Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] update current switch params Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:28:59 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] unlock 1 Oct 03 09:28:59 dm900 enigma2[271]: [SEC-1] sleep 500ms Oct 03 09:28:59 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1392500, inversion off, m_tuning 1 F Oct 03 09:28:59 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1392500, inversion off, m_tuning 1 N Oct 03 09:28:59 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1392500, inversion off, m_tuning 2 N Oct 03 09:29:00 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1392500, inversion off, m_tuning 3 N Oct 03 09:29:00 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1392500, inversion off, m_tuning 4 N Oct 03 09:29:00 dm900 enigma2[271]: [SEC-1] set dynamic current limiting Oct 03 09:29:00 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1392500, inversion off, m_tuning 5 N Oct 03 09:29:00 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1392500, inversion off, m_tuning 6 N Oct 03 09:29:00 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1392500, inversion off, m_tuning 7 N Oct 03 09:29:00 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1392500, inversion off, m_tuning 8 N Oct 03 09:29:00 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1392500, inversion off, m_tuning 9 N Oct 03 09:29:00 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1392500, inversion off, m_tuning 10 N Oct 03 09:29:01 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1392500, inversion off, m_tuning 11 N Oct 03 09:29:01 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1392500, inversion off, m_tuning 12 N Oct 03 09:29:01 dm900 enigma2[271]: FE_TIMEDOUT! ..abort Oct 03 09:29:01 dm900 enigma2[271]: OURSTATE: failed Oct 03 09:29:01 dm900 enigma2[271]: tune failed. Oct 03 09:29:01 dm900 enigma2[271]: DVB service failed to tune - error 1 Oct 03 09:29:01 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: tuning... Tunen fehlgeschlagen! 1507015741.24 Oct 03 09:29:01 dm900 enigma2[271]: create buffer for widget 600 x 140 Oct 03 09:29:01 dm900 enigma2[271]: sdt update done! Oct 03 09:29:01 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:29:01 dm900 enigma2[271]: [ 1] lost lock Oct 03 09:29:01 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1392500, inversion off, m_tuning 0 N Oct 03 09:29:01 dm900 enigma2[271]: action -> InfobarChannelSelection zapUp Oct 03 09:29:01 dm900 enigma2[271]: playing 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] free slot 0 demux 1 for service 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] free service 1:0:19:72:D:85:C00000:0:0:0: Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCIInterfaces] remove last pmt handler for service 1:0:19:72:D:85:C00000:0:0:0: send empty capmt Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCISlot] capmt version equal... increment old was 13![eFileMonitor] 1 events queued Oct 03 09:29:01 dm900 enigma2[271]: new version is 15 Oct 03 09:29:01 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:29:01 dm900 enigma2[271]: (3) slot 0 usecount is now 0 Oct 03 09:29:01 dm900 enigma2[271]: [eDBusInterface::Private::sendEvent 0 Oct 03 09:29:01 dm900 enigma2[271]: [TeleText] service stopped Oct 03 09:29:01 dm900 enigma2[271]: [TeleText] send array('B', [1, 0, 0, 0]) Oct 03 09:29:01 dm900 enigma2[271]: disable dvb subtitles Oct 03 09:29:01 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:29:01 dm900 enigma2[271]: decoder state: play, vpid=-1, apid=-1 Oct 03 09:29:01 dm900 enigma2[271]: DEMUX_STOP - pcr - ok Oct 03 09:29:01 dm900 kernel: stop PCR0 on dmx 1 pid:3ff Oct 03 09:29:01 dm900 kernel: stop VIDEO0 on dmx 1 pid:3ff Oct 03 09:29:01 dm900 kernel: VIDEO0 use saved pts 0x1bacf4c1c (ret 0) Oct 03 09:29:01 dm900 enigma2[271]: DEMUX_STOP - video - ok Oct 03 09:29:01 dm900 kernel: VIDEO0: stop decode Oct 03 09:29:01 dm900 enigma2[271]: VIDEO_STOP - ok Oct 03 09:29:01 dm900 kernel: audio ch0: use saved pts 0x1bacf4188 (0x0) (ret 1) Oct 03 09:29:01 dm900 kernel: audio ch0: decoder stop Oct 03 09:29:01 dm900 enigma2[271]: AUDIO_STOP - ok Oct 03 09:29:01 dm900 enigma2[271]: DEMUX_STOP - audio - ok Oct 03 09:29:01 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:29:01 dm900 kernel: stop AUDIO0 on dmx 1 pid:402 Oct 03 09:29:01 dm900 kernel: audio ch0: xpt stop Oct 03 09:29:01 dm900 kernel: sync0 1fff no decoder left in sync handling so disable Oct 03 09:29:01 dm900 kernel: sync0: was last pcroffset0 user so free it Oct 03 09:29:01 dm900 enigma2[271]: DEMUX_STOP - ttx - ok Oct 03 09:29:01 dm900 enigma2[271]: start release channel timer Oct 03 09:29:01 dm900 enigma2[271]: [TeleText] service started Oct 03 09:29:01 dm900 enigma2[271]: create buffer for widget 1280 x 150 Oct 03 09:29:01 dm900 enigma2[271]: not pauseable. Oct 03 09:29:01 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:29:01 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015741.67 Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] remove channel 0x1a01bc0 Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Oct 03 09:29:01 dm900 enigma2[271]: [eDVBLocalTimeHandler] remove channel 0x1a01bc0 Oct 03 09:29:01 dm900 enigma2[271]: [EPGC] remove channel 0x1a01bc0 Oct 03 09:29:01 dm900 enigma2[271]: [EPGC] abort caching events !! Oct 03 09:29:01 dm900 enigma2[271]: [ 1] preClose Oct 03 09:29:01 dm900 enigma2[271]: allocate channel.. 0006:0085:00c00000 Oct 03 09:29:01 dm900 enigma2[271]: available channel.. 0421:0001:00c00000 Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] new channel 0x1858820! Oct 03 09:29:01 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:29:01 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:29:01 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 11914500H SR 27501000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:29:01 dm900 enigma2[271]: [ 1] tuning to 1314 mhz Oct 03 09:29:01 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] update oparm Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] locked 1 Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] set static current limiting Oct 03 09:29:01 dm900 enigma2[271]: allocate Channel: res 0 Oct 03 09:29:01 dm900 enigma2[271]: allocate demux Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] update current switch params Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:29:01 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] unlock 1 Oct 03 09:29:01 dm900 enigma2[271]: [SEC-1] sleep 500ms Oct 03 09:29:01 dm900 enigma2[271]: setInputSource input1 to A2 Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] new service 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] add demux 1 to slot 0 service 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] use cached! Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAService] demux 1 mask 02 Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCAServiceConn] send 77 bytes to '/tmp/camd.socket' Oct 03 09:29:01 dm900 enigma2[271]: 9f 80 32 49 03 00 81 21 00 2a 01 81 08 00 c0 00 00 00 06 00 85 82 02 02 01 84 02 00 62 85 03 01 41 32 09 04 09 8c fa ba 09 04 09 c4 fb ba 09 04 09 af ff ba 06 00 20 00 00 1b 02 ff 00 00 06 03 02 00 00 06 03 03 00 00 06 03 04 00 00 Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCIInterfaces] addPMTHandler 1:0:19:81:6:85:C00000:0:0:0: Oct 03 09:29:01 dm900 enigma2[271]: 'SKY/00c00000' is in provider list of slot 0... so use it Oct 03 09:29:01 dm900 enigma2[271]: (1)CISlot 0, usecount now 1 Oct 03 09:29:01 dm900 enigma2[271]: setInputSource input1 to CI0 Oct 03 09:29:01 dm900 enigma2[271]: CI Slot 0 setSource(1) Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:29:01 dm900 enigma2[271]: [eDVBCIInterfaces] gotPMT Oct 03 09:29:01 dm900 enigma2[271]: have 1 video stream(s) (02ff), and 1 audio stream(s) (0302), and the pcr pid is 02ff, and the text pid is 0020 Oct 03 09:29:01 dm900 enigma2[271]: allocate demux Oct 03 09:29:01 dm900 enigma2[271]: disable teletext subtitles page ffffffffffffffff (und) Oct 03 09:29:01 dm900 kernel: start PCR0 on dmx 1 pid:2ff index 13 Oct 03 09:29:01 dm900 kernel: start AUDIO0 on dmx 1 pid:302 index 11 Oct 03 09:29:01 dm900 kernel: audio ch0: xpt start Oct 03 09:29:01 dm900 kernel: sync0 is off so we enable it Oct 03 09:29:01 dm900 kernel: sync0: allocated pcroffset 0 Oct 03 09:29:01 dm900 kernel: sync0: demux-index 1 pcr pid 2ff Oct 03 09:29:01 dm900 kernel: sync0: using 13 as pcr pidchannel Oct 03 09:29:01 dm900 kernel: audio ch0: AC3 Oct 03 09:29:01 dm900 kernel: audio ch0: decoder start Oct 03 09:29:01 dm900 kernel: audio ch0 delay 18000 (200 ms) Oct 03 09:29:01 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:29:01 dm900 kernel: start VIDEO0 on dmx 1 pid:2ff index 13 Oct 03 09:29:01 dm900 kernel: VIDEO0: total delay: 18000 (200 ms) Oct 03 09:29:01 dm900 enigma2[271]: decoder state: play, vpid=767, apid=770 Oct 03 09:29:01 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x2ff) - pcr - ok Oct 03 09:29:01 dm900 enigma2[271]: DEMUX_START - pcr - ok Oct 03 09:29:01 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x302) - audio - ok Oct 03 09:29:01 dm900 enigma2[271]: DEMUX_START - audio - ok Oct 03 09:29:01 dm900 enigma2[271]: AUDIO_SET_BYPASS(0) - ok Oct 03 09:29:01 dm900 enigma2[271]: AUDIO_PAUSE - ok Oct 03 09:29:01 dm900 enigma2[271]: AUDIO_PLAY - ok Oct 03 09:29:01 dm900 enigma2[271]: Video Device: /dev/dvb/adapter0/video0 Oct 03 09:29:01 dm900 enigma2[271]: demux device: /dev/dvb/adapter0/demux1 Oct 03 09:29:01 dm900 enigma2[271]: VIDEO_SET_STREAMTYPE 1 - ok Oct 03 09:29:01 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x2ff) - video - ok Oct 03 09:29:01 dm900 enigma2[271]: DEMUX_START - video - ok Oct 03 09:29:01 dm900 enigma2[271]: VIDEO_FREEZE - ok Oct 03 09:29:01 dm900 kernel: VIDEO0: start decode Oct 03 09:29:01 dm900 enigma2[271]: VIDEO_PLAY - ok Oct 03 09:29:01 dm900 enigma2[271]: DMX_SET_PES_FILTER(0x20) - ttx - ok Oct 03 09:29:01 dm900 enigma2[271]: DEMUX_START - ttx - ok Oct 03 09:29:01 dm900 enigma2[271]: VIDEO_SLOWMOTION(0) - ok Oct 03 09:29:01 dm900 enigma2[271]: VIDEO_FAST_FORWARD(0) - ok Oct 03 09:29:01 dm900 enigma2[271]: VIDEO_CONTINUE - ok Oct 03 09:29:01 dm900 enigma2[271]: AUDIO_CONTINUE - ok Oct 03 09:29:01 dm900 enigma2[271]: W/ [InfoBar.__servicePlaying] :: Oct 03 09:29:01 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015741.74 Oct 03 09:29:01 dm900 enigma2[271]: not pauseable. Oct 03 09:29:01 dm900 enigma2[271]: [HbbTV].aitInvalidated Oct 03 09:29:01 dm900 enigma2[271]: nr_read 914 nr_write 2237 Oct 03 09:29:01 dm900 enigma2[271]: sum 3151 prev_sum 3060 Oct 03 09:29:01 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:29:01 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:29:01 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been deleted Oct 03 09:29:01 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 1 F Oct 03 09:29:01 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 1 N Oct 03 09:29:02 dm900 enigma2[271]: [EPGC] cleanup invalid data Oct 03 09:29:02 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 2 N Oct 03 09:29:02 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 3 N Oct 03 09:29:02 dm900 enigma2[271]: [SEC-1] set dynamic current limiting Oct 03 09:29:02 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 4 N Oct 03 09:29:02 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 5 N Oct 03 09:29:02 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 6 N Oct 03 09:29:02 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 7 N Oct 03 09:29:02 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 8 N Oct 03 09:29:02 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 9 N Oct 03 09:29:03 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 10 N Oct 03 09:29:03 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 11 N Oct 03 09:29:03 dm900 enigma2[271]: FE_TIMEDOUT! ..abort Oct 03 09:29:03 dm900 enigma2[271]: OURSTATE: failed Oct 03 09:29:03 dm900 enigma2[271]: tune failed. Oct 03 09:29:03 dm900 enigma2[271]: DVB service failed to tune - error 1 Oct 03 09:29:03 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: tuning... Tunen fehlgeschlagen! 1507015743.24 Oct 03 09:29:03 dm900 enigma2[271]: create buffer for widget 600 x 140 Oct 03 09:29:03 dm900 enigma2[271]: ignore sdt update data.... incorrect transponder tuned!!! Oct 03 09:29:03 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:03 dm900 enigma2[271]: [ 1] lost lock Oct 03 09:29:03 dm900 enigma2[271]: [eFileMonitor] 1 events queued Oct 03 09:29:03 dm900 enigma2[271]: [eFileMonitor] File {/tmp/ecm.info} has been created Oct 03 09:29:03 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:03 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:03 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:03 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:03 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:04 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:04 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:04 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:04 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:04 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:04 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:04 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:05 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:05 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:05 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:05 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:05 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:05 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:05 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:06 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:06 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:06 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:06 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] lost lock Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:07 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:08 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:08 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:08 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:08 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:08 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:08 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:08 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:08 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:09 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:09 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:09 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:09 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:09 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:09 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:09 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:10 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:10 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:10 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:10 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:10 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:10 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:10 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:10 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:11 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:11 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:11 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:11 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:11 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:11 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:12 dm900 enigma2[271]: [ 1] stateLostLock Oct 03 09:29:12 dm900 enigma2[271]: OURSTATE: lost lock, trying to retune Oct 03 09:29:12 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:29:12 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:29:12 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 11914500H SR 27501000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:29:12 dm900 enigma2[271]: [ 1] tuning to 1314 mhz Oct 03 09:29:12 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] update oparm Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] locked 1 Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] set static current limiting Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] update current switch params Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:29:12 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] unlock 1 Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] sleep 500ms Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 1 F Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 1 N Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 2 N Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 3 N Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 4 N Oct 03 09:29:12 dm900 enigma2[271]: [SEC-1] set dynamic current limiting Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 5 N Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 6 N Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 7 N Oct 03 09:29:12 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 8 N Oct 03 09:29:13 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 9 N Oct 03 09:29:13 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 10 N Oct 03 09:29:13 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 11 N Oct 03 09:29:13 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 12 N Oct 03 09:29:13 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 13 N Oct 03 09:29:13 dm900 enigma2[271]: FE_TIMEDOUT! ..abort Oct 03 09:29:13 dm900 enigma2[271]: OURSTATE: failed Oct 03 09:29:13 dm900 enigma2[271]: tune failed. Oct 03 09:29:13 dm900 enigma2[271]: DVB service failed to tune - error 1 Oct 03 09:29:13 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015753.59 Oct 03 09:29:13 dm900 enigma2[271]: ignore sdt update data.... incorrect transponder tuned!!! Oct 03 09:29:13 dm900 enigma2[271]: nr_read 914 nr_write 2311 Oct 03 09:29:13 dm900 enigma2[271]: sum 3225 prev_sum 3151 Oct 03 09:29:13 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:29:13 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:29:13 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:13 dm900 enigma2[271]: [ 1] lost lock Oct 03 09:29:13 dm900 enigma2[271]: [EPGC] cleanupOutdated Oct 03 09:29:13 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:13 dm900 enigma2[271]: It's now Di 03 Okt 2017 09:29:13 CEST Oct 03 09:29:13 dm900 enigma2[271]: next real activation is Di 03 Okt 2017 09:50:00 CEST Oct 03 09:29:13 dm900 enigma2[271]: [timer.py] next activation: 1507015853 (in 99050 ms) Oct 03 09:29:14 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:14 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:14 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:14 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:14 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:14 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:14 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:15 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:15 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:15 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:15 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:15 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:15 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:15 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:16 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:16 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:16 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:16 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:16 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:16 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:16 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:17 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:17 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:17 dm900 enigma2[271]: [ 1] lost lock Oct 03 09:29:17 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:17 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:17 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:17 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:17 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:17 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:18 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:18 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:18 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:18 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:18 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:18 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:18 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:19 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:19 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:19 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:19 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:19 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:19 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:20 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:20 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:20 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:20 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:20 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:20 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:20 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:20 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:21 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:21 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:21 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:21 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:21 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:21 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:21 dm900 enigma2[271]: no ancillary data in audio stream... abort radiotext pes parser Oct 03 09:29:21 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:22 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:22 dm900 enigma2[271]: [ 1] stateLostLock Oct 03 09:29:22 dm900 enigma2[271]: OURSTATE: lost lock, trying to retune Oct 03 09:29:22 dm900 enigma2[271]: [ 1] tune type 1 Oct 03 09:29:22 dm900 enigma2[271]: RotorCmd ffffffff, lastRotorCmd ffffffff Oct 03 09:29:22 dm900 enigma2[271]: [ 1] prepare_sat System DVB-S2 Freq 11914500H SR 27501000 INV Auto FEC 9/10 orbpos 192 modulation QPSK pilot Off, rolloff 0.35 Oct 03 09:29:22 dm900 enigma2[271]: [ 1] tuning to 1314 mhz Oct 03 09:29:22 dm900 enigma2[271]: OURSTATE: tuning Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] update oparm Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] lock 0 Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] locked 1 Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] set static current limiting Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] invalidate current switch params Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] update current switch params Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] startTuneTimeout 5000 Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] setFrontend 1 Oct 03 09:29:22 dm900 enigma2[271]: [ 1] setting frontend Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] unlock 1 Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] sleep 500ms Oct 03 09:29:22 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 1 F Oct 03 09:29:22 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 1 N Oct 03 09:29:22 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 2 N Oct 03 09:29:22 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 3 N Oct 03 09:29:22 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 4 N Oct 03 09:29:22 dm900 enigma2[271]: [SEC-1] set dynamic current limiting Oct 03 09:29:22 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 5 N Oct 03 09:29:22 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 6 N Oct 03 09:29:23 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 7 N Oct 03 09:29:23 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 8 N Oct 03 09:29:23 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 9 N Oct 03 09:29:23 dm900 enigma2[271]: [ 1] fe event: status 0, freq 1314500, inversion off, m_tuning 10 N Oct 03 09:29:23 dm900 enigma2[271]: [ 1] fe event: status 1, freq 1314500, inversion off, m_tuning 11 N Oct 03 09:29:23 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 12 N Oct 03 09:29:23 dm900 enigma2[271]: FE_TIMEDOUT! ..abort Oct 03 09:29:23 dm900 enigma2[271]: OURSTATE: failed Oct 03 09:29:23 dm900 enigma2[271]: tune failed. Oct 03 09:29:23 dm900 enigma2[271]: DVB service failed to tune - error 1 Oct 03 09:29:23 dm900 enigma2[271]: I/ [InfoBar.setPlaybackState] :: None None 1507015763.66 Oct 03 09:29:23 dm900 enigma2[271]: ignore sdt update data.... incorrect transponder tuned!!! Oct 03 09:29:23 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:23 dm900 enigma2[271]: [ 1] lost lock Oct 03 09:29:23 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:24 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:24 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:24 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:24 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:24 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:24 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:24 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:25 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:25 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:25 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:25 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:25 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:25 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:25 dm900 enigma2[271]: nr_read 914 nr_write 2375 Oct 03 09:29:25 dm900 enigma2[271]: sum 3289 prev_sum 3225 Oct 03 09:29:25 dm900 enigma2[271]: hdd was accessed since previous check! Oct 03 09:29:25 dm900 enigma2[271]: [IDLE] 0 120 False Oct 03 09:29:25 dm900 enigma2[271]: [ 1] fe event: status 21, freq 1314500, inversion off, m_tuning 0 N Oct 03 09:29:26 dm900 enigma2[271]: [ 1] fe event: status 20, freq 1314500, inversion off, m_tuning 0 N