-- Logs begin at Do 1970-01-01 01:00:00 CET. --
Sep 15 00:20:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:20:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:20:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:20:06 dm900 enigma2[333]: [IDLE] 29.9999301434 300 False
Sep 15 00:20:06 dm900 enigma2[333]: nr_read 514 nr_write 178
Sep 15 00:20:06 dm900 enigma2[333]: sum 692 prev_sum 603
Sep 15 00:20:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:20:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:20:22 dm900 enigma2[333]: [eFileMonitor] 1 events queued
Sep 15 00:20:22 dm900 enigma2[333]: [eFileMonitor] File {/tmp/enigma2.log} has been created
Sep 15 00:20:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:20:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:20:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:20:36 dm900 enigma2[333]: [IDLE] 60.0007231236 300 False
Sep 15 00:20:36 dm900 enigma2[333]: nr_read 515 nr_write 447
Sep 15 00:20:36 dm900 enigma2[333]: sum 962 prev_sum 692
Sep 15 00:20:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:20:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:20:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:20:39 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:20:39 CEST
Sep 15 00:20:40 dm900 enigma2[333]: [TIMER] activating state 1
Sep 15 00:20:40 dm900 enigma2[333]: recording service: <enigma.eServiceReference; proxy of <Swig Object of type 'eServiceReference *' at 0xa23f1968> >
Sep 15 00:20:40 dm900 enigma2[333]: record: 0
Sep 15 00:20:40 dm900 enigma2[333]: [add callback]
Sep 15 00:20:40 dm900 enigma2[333]: begin_date:  20170915 0021
Sep 15 00:20:40 dm900 enigma2[333]: service_name:  ZDF HD
Sep 15 00:20:40 dm900 enigma2[333]: name: heute+
Sep 15 00:20:40 dm900 enigma2[333]: description:
Sep 15 00:20:40 dm900 enigma2[333]: [TIMER] Filename calculated as: '/media/hdd/movie/20170915 0021 - ZDF HD - heute+.ts'
Sep 15 00:20:40 dm900 enigma2[333]: allocate channel.. 03f3:0001:00c00000
Sep 15 00:20:40 dm900 enigma2[333]: available channel.. 03fb:0001:00c00000
Sep 15 00:20:40 dm900 enigma2[333]: [    1] opening frontend
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCAService] new channel 0x2b573e8!
Sep 15 00:20:40 dm900 enigma2[333]: [    1] tune type 1
Sep 15 00:20:40 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:20:40 dm900 enigma2[333]: [    1] prepare_sat System DVB-S2 Freq 11361750H SR 22000000 INV Auto FEC 2/3 orbpos 192 modulation 8PSK pilot Auto, rolloff 0.35
Sep 15 00:20:40 dm900 enigma2[333]: [    1] tuning to 1611 mhz
Sep 15 00:20:40 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-1] update oparm
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-1] lock 0
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-1] locked 0
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:20:40 dm900 enigma2[333]: allocate Channel: res 0
Sep 15 00:20:40 dm900 enigma2[333]: allocate demux[SEC-1] invalidate current switch params
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-1] update current switch params
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-1] startTuneTimeout 5000
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-1] setFrontend 1
Sep 15 00:20:40 dm900 enigma2[333]: [    1] setting frontend
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-1] unlock 0
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-1] sleep 500ms
Sep 15 00:20:40 dm900 enigma2[333]: setInputSource input1 to A2
Sep 15 00:20:40 dm900 enigma2[333]: RECORD service event 6
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBMetaParser::updateMetaDb] database meta info outdated, updating!
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBMetaParser::updateMetaDb] setting tags to
Sep 15 00:20:40 dm900 enigma2[333]: [eMediaDatabase] Error in query:
Sep 15 00:20:40 dm900 enigma2[333]: driverText:
Sep 15 00:20:40 dm900 enigma2[333]: databaseText:
Sep 15 00:20:40 dm900 enigma2[333]: query epg event id 29036
Sep 15 00:20:40 dm900 enigma2[333]: found event.. store to disc
Sep 15 00:20:40 dm900 enigma2[333]: [TIMER] prepare ok, waiting for begin
Sep 15 00:20:40 dm900 enigma2[333]: [timer.py] next activation: 1505427660 (in 19841 ms)
Sep 15 00:20:40 dm900 enigma2[333]: [    1] fe event: status 0, freq 1611750, inversion off, m_tuning 1 F
Sep 15 00:20:40 dm900 enigma2[333]: [    1] fe event: status 1, freq 1611750, inversion off, m_tuning 1 N
Sep 15 00:20:40 dm900 enigma2[333]: [    1] fe event: status 0, freq 1611750, inversion off, m_tuning 2 N
Sep 15 00:20:40 dm900 enigma2[333]: [    1] fe event: status 1f, freq 1611750, inversion off, m_tuning 3 N
Sep 15 00:20:40 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCAService] channel 0x2b573e8 running
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBLocalTimeHandler] channel 0x2b573e8 running
Sep 15 00:20:40 dm900 enigma2[333]: no version filtering
Sep 15 00:20:40 dm900 enigma2[333]: 0014:  70 00 00 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mask:  fc 00 00 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: [EPGC] channel 0x2b573e8 running
Sep 15 00:20:40 dm900 enigma2[333]: [EPGC] next update in 2 sec
Sep 15 00:20:40 dm900 enigma2[333]: stop release channel timer
Sep 15 00:20:40 dm900 enigma2[333]: RECORD service event 7
Sep 15 00:20:40 dm900 enigma2[333]: tuned..
Sep 15 00:20:40 dm900 enigma2[333]: no version filtering
Sep 15 00:20:40 dm900 enigma2[333]: 0012:  4e 2b 66 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mask:  ff ff ff 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: [TIMER] record event 2
Sep 15 00:20:40 dm900 enigma2[333]: ok ... now we start!!
Sep 15 00:20:40 dm900 enigma2[333]: no version filtering
Sep 15 00:20:40 dm900 enigma2[333]: 0000:  00 00 00 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mask:  ff 00 00 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: + 1/1 TID 00
Sep 15 00:20:40 dm900 enigma2[333]: done!
Sep 15 00:20:40 dm900 enigma2[333]: PATready
Sep 15 00:20:40 dm900 enigma2[333]: use pmtpid 17d4 for service_id 2b66
Sep 15 00:20:40 dm900 enigma2[333]: no version filtering
Sep 15 00:20:40 dm900 enigma2[333]: 17d4:  02 2b 66 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mask:  ff ff ff 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:20:40 dm900 enigma2[333]: doing version filtering
Sep 15 00:20:40 dm900 enigma2[333]: 0000:  00 00 00 01 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mask:  ff 00 00 3f 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mode:  00 00 00 3e 00 00
Sep 15 00:20:40 dm900 enigma2[333]: + 1/1 TID 02
Sep 15 00:20:40 dm900 enigma2[333]: done!
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCAService] new service 1:0:19:2B66:3F3:1:C00000:0:0:0:
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCAService] add demux 1 to slot 0 service 1:0:19:2B66:3F3:1:C00000:0:0:0:
Sep 15 00:20:40 dm900 enigma2[333]: dvb subtitle  PID 17f3 with wrong subtitling type (24)... force 0x10!!
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCAService] demux 1 mask 02
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCAServiceConn] send 79 bytes to '/tmp/camd.socket'
Sep 15 00:20:40 dm900 enigma2[333]: 9f 80 32 4b 03 2b 66 01 00 18 01 81 08 00 c0 00 00 03 f3 00 01 82 02 02 01 84 02 17 d4 85 03 01 41 32 1b 17 de 00 00 03 17 e8 00 00 03 17 e9 00 00 06 17 ea 00 00 03 17 eb 00 00 06 17 f2 00 00 06 17 f3 00 00 06 17 f4 00 00 05 18 1a 00 00
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCAServiceConn] send 79 bytes to '/var/run/ca/ci0.socket'
Sep 15 00:20:40 dm900 enigma2[333]: 9f 80 32 4b 03 2b 66 01 00 18 01 81 08 00 c0 00 00 03 f3 00 01 82 02 02 01 84 02 17 d4 85 03 01 41 32 1b 17 de 00 00 03 17 e8 00 00 03 17 e9 00 00 06 17 ea 00 00 03 17 eb 00 00 06 17 f2 00 00 06 17 f3 00 00 06 17 f4 00 00 05 18 1a 00 00
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCIInterfaces] addPMTHandler 1:0:19:2B66:3F3:1:C00000:0:0:0:
Sep 15 00:20:40 dm900 enigma2[333]: [eDVBCIInterfaces] gotPMT
Sep 15 00:20:40 dm900 enigma2[333]: RECORD service event 5
Sep 15 00:20:40 dm900 enigma2[333]: [TIMER] record event 6
Sep 15 00:20:40 dm900 enigma2[333]: doing version filtering
Sep 15 00:20:40 dm900 enigma2[333]: 17d4:  02 2b 66 01 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mask:  ff ff ff 3f 00 00
Sep 15 00:20:40 dm900 enigma2[333]: mode:  00 00 00 3e 00 00
Sep 15 00:20:40 dm900 enigma2[333]: sdt update done!
Sep 15 00:20:40 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:20:40 dm900 enigma2[333]: -+ 1/2 TID 4e
Sep 15 00:20:41 dm900 enigma2[333]: ++ 2/2 TID 4e
Sep 15 00:20:41 dm900 enigma2[333]: done!
Sep 15 00:20:41 dm900 enigma2[333]: event id of next EIT event matched record event...
Sep 15 00:20:41 dm900 enigma2[333]: written EIT based /media/hdd/movie/20170915 0021 - ZDF HD - heute+.eit
Sep 15 00:20:41 dm900 enigma2[333]: [eDVBServiceRecord] now running: Markus Lanz (4500 seconds) (NOT RECORDING YET)
Sep 15 00:20:41 dm900 enigma2[333]: [TIMER] record event 9
Sep 15 00:20:41 dm900 enigma2[333]: doing version filtering
Sep 15 00:20:41 dm900 enigma2[333]: 0012:  4e 2b 66 3f 00 00
Sep 15 00:20:41 dm900 enigma2[333]: mask:  ff ff ff 3f 00 00
Sep 15 00:20:41 dm900 enigma2[333]: mode:  00 00 00 3e 00 00
Sep 15 00:20:42 dm900 enigma2[333]: [eDVBLocalTimeHandler] Receiver time is 'Fri Sep 15 00:20:42 2017'
Sep 15 00:20:42 dm900 enigma2[333]: [eDVBLocalTimeHandler] Transponder time is 'Fri Sep 15 00:20:41 2017'
Sep 15 00:20:42 dm900 enigma2[333]: [eDVBLocalTimeHandler] diff is -1
Sep 15 00:20:42 dm900 enigma2[333]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Sep 15 00:20:42 dm900 enigma2[333]: [eDVBLocalTimeHandler] update RTC
Sep 15 00:20:42 dm900 enigma2[333]: [eDVBLocalTimeHandler] time_difference is -1
Sep 15 00:20:42 dm900 enigma2[333]: [eDVBLocalTimeHandler] update Linux Time
Sep 15 00:20:42 dm900 enigma2[333]: [EPGC] start caching events(1505427642)
Sep 15 00:20:49 dm900 enigma2[333]: [EPGC] abort non avail schedule other reading
Sep 15 00:20:49 dm900 enigma2[333]: [EPGC] abort non avail viasat reading
Sep 15 00:20:49 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:20:49 CEST
Sep 15 00:20:49 dm900 enigma2[333]: [timer.py] next activation: 1505427749 (in 99009 ms)
Sep 15 00:20:50 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:20:50 CEST
Sep 15 00:20:50 dm900 enigma2[333]: [timer.py] next activation: 1505427750 (in 99008 ms)
Sep 15 00:20:55 dm900 enigma2[333]: [InfoBar] quickSelectGlobal key:  down
Sep 15 00:20:55 dm900 enigma2[333]: [InfoBar] exec from Screens.InfoBar import InfoBar
Sep 15 00:20:55 dm900 enigma2[333]: if InfoBar and InfoBar.instance:
Sep 15 00:20:55 dm900 enigma2[333]:         InfoBar.switchChannelDown(InfoBar.instance)
Sep 15 00:20:55 dm900 enigma2[333]: allocating new converter!
Sep 15 00:20:55 dm900 enigma2[333]: reused converter!
Sep 15 00:20:55 dm900 enigma2[333]: reused converter!
Sep 15 00:20:55 dm900 enigma2[333]: reused converter!
Sep 15 00:20:55 dm900 enigma2[333]: create buffer for widget 1280 x 720
Sep 15 00:20:57 dm900 enigma2[333]: [EPGC] schedule finished(1505427657)
Sep 15 00:20:57 dm900 enigma2[333]: [EPGC] nownext finished(1505427657)
Sep 15 00:20:57 dm900 enigma2[333]: [EPGC] stop caching events(1505427657)
Sep 15 00:20:57 dm900 enigma2[333]: [EPGC] next update in 60 min
Sep 15 00:20:58 dm900 enigma2[333]: action ->  ChannelSelectBaseActions selectServiceDown
Sep 15 00:20:58 dm900 enigma2[333]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap?
Sep 15 00:20:58 dm900 enigma2[333]: action ->  WizardActions down
Sep 15 00:20:58 dm900 enigma2[333]: unknown action WizardActions/down! typo in keymap?
Sep 15 00:20:58 dm900 enigma2[333]: action ->  ChannelSelectBaseActions selectServiceDown
Sep 15 00:20:58 dm900 enigma2[333]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap?
Sep 15 00:20:58 dm900 enigma2[333]: action ->  WizardActions down
Sep 15 00:20:58 dm900 enigma2[333]: unknown action WizardActions/down! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: action ->  ChannelSelectBaseActions selectServiceDown
Sep 15 00:20:59 dm900 enigma2[333]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: action ->  WizardActions down
Sep 15 00:20:59 dm900 enigma2[333]: unknown action WizardActions/down! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: action ->  ChannelSelectBaseActions selectServiceDown
Sep 15 00:20:59 dm900 enigma2[333]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: action ->  WizardActions down
Sep 15 00:20:59 dm900 enigma2[333]: unknown action WizardActions/down! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: action ->  ChannelSelectBaseActions selectServiceDown
Sep 15 00:20:59 dm900 enigma2[333]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: action ->  WizardActions down
Sep 15 00:20:59 dm900 enigma2[333]: unknown action WizardActions/down! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: action ->  ChannelSelectBaseActions selectServiceDown
Sep 15 00:20:59 dm900 enigma2[333]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: action ->  WizardActions down
Sep 15 00:20:59 dm900 enigma2[333]: unknown action WizardActions/down! typo in keymap?
Sep 15 00:20:59 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:20:59 CEST
Sep 15 00:20:59 dm900 enigma2[333]: [timer.py] next activation: 1505427660 (in 9 ms)
Sep 15 00:21:00 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:20:59 CEST
Sep 15 00:21:00 dm900 enigma2[333]: [TIMER] activating state 2
Sep 15 00:21:00 dm900 enigma2[333]: [TIMER] start recording
Sep 15 00:21:00 dm900 enigma2[333]: [TIMER] record event 0
Sep 15 00:21:00 dm900 enigma2[333]: AddPopup, id = None domain = RecordTimer
Sep 15 00:21:00 dm900 enigma2[333]: Recording to /media/hdd/movie/20170915 0021 - ZDF HD - heute+.ts...
Sep 15 00:21:00 dm900 enigma2[333]: start recording...
Sep 15 00:21:00 dm900 enigma2[333]: RECORD: have 1 video stream(s) (17de), and 4 audio stream(s) (17e8, 17e9, 17ea, 17eb) (17f3), and the pcr pid is 17de, and the text pid is 17f2
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 0000
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 17d4
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 17de
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 17e8
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 17e9
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 17ea
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 17eb
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 17f2
Sep 15 00:21:00 dm900 enigma2[333]: ADD PID: 17f3
Sep 15 00:21:00 dm900 enigma2[333]: before: 1
Sep 15 00:21:00 dm900 enigma2[333]: after: 1
Sep 15 00:21:00 dm900 enigma2[333]: setIoPrio realtime level 7 ok
Sep 15 00:21:00 dm900 enigma2[333]: eRecordFileThread (direct io) START
Sep 15 00:21:00 dm900 enigma2[333]: record write thread succesfully created!
Sep 15 00:21:00 dm900 enigma2[333]: [TIMER] record event 4
Sep 15 00:21:00 dm900 enigma2[333]: [AUTOPIN] recording started 1:0:19:2B66:3F3:1:C00000:0:0:0:
Sep 15 00:21:00 dm900 enigma2[333]: [eFileMonitor] 1 events queued
Sep 15 00:21:00 dm900 enigma2[333]: next real activation is Fr 15 Sep 2017 00:25:00 CEST
Sep 15 00:21:00 dm900 enigma2[333]: [timer.py] next activation: 1505427760 (in 99948 ms)
Sep 15 00:21:00 dm900 enigma2[333]: [eFileMonitor] File {/tmp/record.1:0:19:2B66:3F3:1:C00000:0:0:0:} has been created
Sep 15 00:21:00 dm900 enigma2[333]: setIoPrio realtime level 7 ok
Sep 15 00:21:00 dm900 enigma2[333]: action ->  ChannelSelectBaseActions selectServiceDown
Sep 15 00:21:00 dm900 enigma2[333]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap?
Sep 15 00:21:00 dm900 enigma2[333]: action ->  WizardActions down
Sep 15 00:21:00 dm900 enigma2[333]: unknown action WizardActions/down! typo in keymap?
Sep 15 00:21:01 dm900 enigma2[333]: action ->  OkCancelActions ok
Sep 15 00:21:01 dm900 enigma2[333]: playing 1:0:1:11F8:3FD:1:C00000:0:0:0:
Sep 15 00:21:01 dm900 enigma2[333]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:283D:3FB:1:C00000:0:0:0:
Sep 15 00:21:01 dm900 enigma2[333]: [eDVBCAService] don't send the same capmt twice
Sep 15 00:21:01 dm900 enigma2[333]: [AUTOPIN] STOPPED
Sep 15 00:21:01 dm900 enigma2[333]: [eDBusInterface::Private::sendEvent 0
Sep 15 00:21:01 dm900 enigma2[333]: FIXMEE unknown frontend type in FrontendInfo getValue Converter return 0 (SAT) None
Sep 15 00:21:01 dm900 enigma2[333]: [TeleText] service stopped
Sep 15 00:21:01 dm900 enigma2[333]: [TeleText] send array('B', [1, 0, 0, 0])
Sep 15 00:21:01 dm900 enigma2[333]: [AUTOPIN] END
Sep 15 00:21:01 dm900 enigma2[333]: disable dvb subtitles
Sep 15 00:21:01 dm900 enigma2[333]: disable teletext subtitles page ffffffffffffffff (und)
Sep 15 00:21:01 dm900 enigma2[333]: decoder state: play, vpid=-1, apid=-1
Sep 15 00:21:01 dm900 enigma2[333]: DEMUX_STOP - pcr - ok
Sep 15 00:21:01 dm900 enigma2[333]: DEMUX_STOP - video - ok
Sep 15 00:21:01 dm900 enigma2[333]: VIDEO_STOP - ok
Sep 15 00:21:01 dm900 enigma2[333]: AUDIO_STOP - ok
Sep 15 00:21:01 dm900 enigma2[333]: DEMUX_STOP - audio - ok
Sep 15 00:21:01 dm900 enigma2[333]: AUDIO_CONTINUE - ok
Sep 15 00:21:01 dm900 enigma2[333]: DEMUX_STOP - ttx - ok
Sep 15 00:21:01 dm900 enigma2[333]: [TeleText] service started
Sep 15 00:21:01 dm900 enigma2[333]: [AUTOPIN] START
Sep 15 00:21:01 dm900 enigma2[333]: not pauseable.
Sep 15 00:21:01 dm900 enigma2[333]: W/  [InfoBar.__servicePlaying] :: <no detail>
Sep 15 00:21:01 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505427661.33
Sep 15 00:21:01 dm900 enigma2[333]: allocate channel.. 03fd:0001:00c00000
Sep 15 00:21:01 dm900 enigma2[333]: available channel.. 03fb:0001:00c00000
Sep 15 00:21:01 dm900 enigma2[333]: available channel.. 03f3:0001:00c00000
Sep 15 00:21:01 dm900 enigma2[333]: [    2] opening frontend
Sep 15 00:21:01 dm900 enigma2[333]: [eDVBCAService] new channel 0x2a2db40!
Sep 15 00:21:01 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:21:01 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:21:01 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:21:01 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:21:01 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:21:01 dm900 enigma2[333]: allocate Channel: res 0
Sep 15 00:21:01 dm900 enigma2[333]: allocate demux
Sep 15 00:21:01 dm900 enigma2[333]: setInputSource input2 to A3
Sep 15 00:21:01 dm900 enigma2[333]: have 1 video stream(s) (00ff), and 1 audio stream(s) (0100), and the pcr pid is 00ff, and the text pid is ffffffff
Sep 15 00:21:01 dm900 enigma2[333]: allocate demux
Sep 15 00:21:01 dm900 enigma2[333]: disable teletext subtitles page ffffffffffffffff (und)
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:21:01 dm900 enigma2[333]: PES filter: DMX_SET_PES_FILTER - Invalid argument
Sep 15 00:21:01 dm900 enigma2[333]: decoder state: play, vpid=255, apid=256
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] lock 0DMX_SET_PES_FILTER(0xff) - pcr - ok
Sep 15 00:21:01 dm900 enigma2[333]: DEMUX_START - pcr -
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:21:01 dm900 enigma2[333]: ok
Sep 15 00:21:01 dm900 enigma2[333]: DMX_SET_PES_FILTER(0x100) - audio - [SEC-0] set static current limiting
Sep 15 00:21:01 dm900 enigma2[333]: ok
Sep 15 00:21:01 dm900 enigma2[333]: DEMUX_START - audio - ok
Sep 15 00:21:01 dm900 enigma2[333]: AUDIO_SET_BYPASS(1) - [SEC-2] set tuner input A
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:21:01 dm900 enigma2[333]: ok
Sep 15 00:21:01 dm900 enigma2[333]: AUDIO_PAUSE - ok
Sep 15 00:21:01 dm900 enigma2[333]: AUDIO_PLAY - [SEC-0] setVoltage 18V
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:21:01 dm900 enigma2[333]: ok
Sep 15 00:21:01 dm900 enigma2[333]: Video Device: /dev/dvb/adapter0/video0
Sep 15 00:21:01 dm900 enigma2[333]: demux device: /dev/dvb/adapter0/demux2
Sep 15 00:21:01 dm900 enigma2[333]: VIDEO_SET_STREAMTYPE 0 - ok
Sep 15 00:21:01 dm900 enigma2[333]: DMX_SET_PES_FILTER(0xff) - video - ok
Sep 15 00:21:01 dm900 enigma2[333]: DEMUX_START - video - ok
Sep 15 00:21:01 dm900 enigma2[333]: VIDEO_FREEZE - ok
Sep 15 00:21:01 dm900 enigma2[333]: VIDEO_PLAY - ok
Sep 15 00:21:01 dm900 enigma2[333]: VIDEO_SLOWMOTION(0) - ok
Sep 15 00:21:01 dm900 enigma2[333]: VIDEO_FAST_FORWARD(0) - ok
Sep 15 00:21:01 dm900 enigma2[333]: VIDEO_CONTINUE - ok
Sep 15 00:21:01 dm900 enigma2[333]: AUDIO_CONTINUE - ok
Sep 15 00:21:01 dm900 enigma2[333]: W/  [InfoBar.__servicePlaying] :: <no detail>
Sep 15 00:21:01 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505427661.36
Sep 15 00:21:01 dm900 enigma2[333]: [AUTOPIN] SEEKABLE changed
Sep 15 00:21:01 dm900 enigma2[333]: not pauseable.
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:21:01 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:21:01 dm900 enigma2[333]: I/  [InfoBar.__restoreState] :: <no detail>
Sep 15 00:21:01 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] ::   1505427661.37
Sep 15 00:21:01 dm900 enigma2[333]: create buffer for widget 1280 x 200
Sep 15 00:21:01 dm900 enigma2[333]: [NotificationQueue::popNotification] domain RecordTimer deferred_callable: False
Sep 15 00:21:01 dm900 enigma2[333]: create buffer for widget 487 x 178
Sep 15 00:21:01 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:21:01 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:21:01 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:21:01 dm900 enigma2[333]: [eDVBCAService] channel 0x2a2db40 running
Sep 15 00:21:01 dm900 enigma2[333]: [eDVBLocalTimeHandler] channel 0x2a2db40 running
Sep 15 00:21:01 dm900 enigma2[333]: no version filtering
Sep 15 00:21:01 dm900 enigma2[333]: 0014:  70 00 00 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: mask:  fc 00 00 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: [EPGC] channel 0x2a2db40 running
Sep 15 00:21:01 dm900 enigma2[333]: stop release channel timer
Sep 15 00:21:01 dm900 enigma2[333]: [EPGC] next update in 48 min
Sep 15 00:21:01 dm900 enigma2[333]: no version filtering
Sep 15 00:21:01 dm900 enigma2[333]: 0012:  4e 11 f8 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: mask:  ff ff ff 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: ok ... now we start!!
Sep 15 00:21:01 dm900 enigma2[333]: no version filtering
Sep 15 00:21:01 dm900 enigma2[333]: 0000:  00 00 00 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: mask:  ff 00 00 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:21:01 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:21:01 dm900 enigma2[333]: VIDEO_GET_EVENT - ok
Sep 15 00:21:02 dm900 enigma2[333]: [    0] fe event: status 1f, freq 1743750, inversion off, m_tuning 0 N
Sep 15 00:21:02 dm900 enigma2[333]: VIDEO_GET_EVENT - ok
Sep 15 00:21:02 dm900 enigma2[333]: VIDEO_GET_EVENT - ok
Sep 15 00:21:02 dm900 enigma2[333]: unhandled DVBAPI Video Event 32
Sep 15 00:21:02 dm900 enigma2[333]: +- 1/2 TID 4e
Sep 15 00:21:02 dm900 enigma2[333]: + 1/1 TID 00
Sep 15 00:21:02 dm900 enigma2[333]: done!
Sep 15 00:21:02 dm900 enigma2[333]: PATready
Sep 15 00:21:02 dm900 enigma2[333]: use pmtpid 0060 for service_id 11f8
Sep 15 00:21:02 dm900 enigma2[333]: no version filtering
Sep 15 00:21:02 dm900 enigma2[333]: 0060:  02 11 f8 00 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mask:  ff ff ff 00 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:21:02 dm900 enigma2[333]: doing version filtering
Sep 15 00:21:02 dm900 enigma2[333]: 0000:  00 00 00 07 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mask:  ff 00 00 3f 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mode:  00 00 00 3e 00 00
Sep 15 00:21:02 dm900 enigma2[333]: VIDEO_GET_EVENT - ok
Sep 15 00:21:02 dm900 enigma2[333]: + 1/1 TID 02
Sep 15 00:21:02 dm900 enigma2[333]: done!
Sep 15 00:21:02 dm900 enigma2[333]: [eDVBCAService] new service 1:0:1:11F8:3FD:1:C00000:0:0:0:
Sep 15 00:21:02 dm900 enigma2[333]: [eDVBCAService] add demux 2 to slot 0 service 1:0:1:11F8:3FD:1:C00000:0:0:0:
Sep 15 00:21:02 dm900 enigma2[333]: HBBTV broadcast data on pid 1206
Sep 15 00:21:02 dm900 enigma2[333]: [eDVBCAService] demux 2 mask 04
Sep 15 00:21:02 dm900 enigma2[333]: [eDVBCAServiceConn] send 54 bytes to '/tmp/camd.socket'
Sep 15 00:21:02 dm900 enigma2[333]: 9f 80 32 32 03 11 f8 03 00 18 01 81 08 00 c0 00 00 03 fd 00 01 82 02 04 02 84 02 00 60 85 03 02 41 33 02 00 ff 00 00 03 01 00 00 00 05 01 05 00 00 0b 12 06 00 00
Sep 15 00:21:02 dm900 enigma2[333]: [eDVBCAServiceConn] send 54 bytes to '/var/run/ca/ci0.socket'
Sep 15 00:21:02 dm900 enigma2[333]: 9f 80 32 32 03 11 f8 03 00 18 01 81 08 00 c0 00 00 03 fd 00 01 82 02 04 02 84 02 00 60 85 03 02 41 33 02 00 ff 00 00 03 01 00 00 00 05 01 05 00 00 0b 12 06 00 00
Sep 15 00:21:02 dm900 enigma2[333]: [eDVBCIInterfaces] addPMTHandler 1:0:1:11F8:3FD:1:C00000:0:0:0:
Sep 15 00:21:02 dm900 enigma2[333]: [eDVBCIInterfaces] gotPMT
Sep 15 00:21:02 dm900 enigma2[333]: eventNewProgramInfo 0 0
Sep 15 00:21:02 dm900 enigma2[333]: start ait on pid 0105
Sep 15 00:21:02 dm900 enigma2[333]: no version filtering
Sep 15 00:21:02 dm900 enigma2[333]: 0105:  74 00 00 00 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mask:  ff 00 00 00 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mode:  00 00 00 00 00 00
Sep 15 00:21:02 dm900 enigma2[333]: have 1 video stream(s) (00ff), and 1 audio stream(s) (0100), and the pcr pid is 00ff, and the text pid is ffffffff
Sep 15 00:21:02 dm900 enigma2[333]: PES filter: DMX_SET_PES_FILTER - Invalid argument
Sep 15 00:21:02 dm900 enigma2[333]: decoder state: play, vpid=255, apid=256
Sep 15 00:21:02 dm900 enigma2[333]: VIDEO_SLOWMOTION(0) - ok
Sep 15 00:21:02 dm900 enigma2[333]: VIDEO_FAST_FORWARD(0) - ok
Sep 15 00:21:02 dm900 enigma2[333]: VIDEO_CONTINUE - ok
Sep 15 00:21:02 dm900 enigma2[333]: AUDIO_CONTINUE - ok
Sep 15 00:21:02 dm900 enigma2[333]: W/  [InfoBar.__servicePlaying] :: <no detail>
Sep 15 00:21:02 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505427662.58
Sep 15 00:21:02 dm900 enigma2[333]: [eDBusInterface::Private::sendEvent 1
Sep 15 00:21:02 dm900 enigma2[333]: HBBTV broadcast data on pid 1206
Sep 15 00:21:02 dm900 enigma2[333]: [TeleText] serviceInfoChanged
Sep 15 00:21:02 dm900 enigma2[333]: [TeleText] TXT PID -1 DEMUX 2
Sep 15 00:21:02 dm900 enigma2[333]: [AUTOPIN] Info
Sep 15 00:21:02 dm900 enigma2[333]: doing version filtering
Sep 15 00:21:02 dm900 enigma2[333]: 0060:  02 11 f8 03 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mask:  ff ff ff 3f 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mode:  00 00 00 3e 00 00
Sep 15 00:21:02 dm900 enigma2[333]: ++ 2/2 TID 4e
Sep 15 00:21:02 dm900 enigma2[333]: done!
Sep 15 00:21:02 dm900 enigma2[333]: W/  [InfoBar.__servicePlaying] :: <no detail>
Sep 15 00:21:02 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505427662.98
Sep 15 00:21:02 dm900 enigma2[333]: doing version filtering
Sep 15 00:21:02 dm900 enigma2[333]: 0012:  4e 11 f8 23 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mask:  ff ff ff 3f 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mode:  00 00 00 3e 00 00
Sep 15 00:21:02 dm900 enigma2[333]: + 1/1 TID 74
Sep 15 00:21:02 dm900 enigma2[333]: done!
Sep 15 00:21:02 dm900 enigma2[333]: [eHbbtv] aitChanged pid=0x105
Sep 15 00:21:02 dm900 enigma2[333]: [eOipfApplication] Profile 0000, Version 1.1.1
Sep 15 00:21:02 dm900 enigma2[333]: [eOipfApplication] deu:a.tv
Sep 15 00:21:02 dm900 enigma2[333]: [eHbbtv] New Application: a.tv (orgid=672, appid=2, urlBase=http://hbbtv.bmt-technik.de/dvb-s/sesps/a.tv/, initalPath=index.html, controlCode=1, visibility=2)
Sep 15 00:21:02 dm900 enigma2[333]: [eHbbtv] Resolving Application Locator: dvb://current.ait/2a0.2
Sep 15 00:21:02 dm900 enigma2[333]: [eHbbtv] We have 1 app(s) for service 1.1021.4600
Sep 15 00:21:02 dm900 enigma2[333]: [eHbbtv] 'dvb://current.ait/2a0.2' resolved to 'http://hbbtv.bmt-technik.de/dvb-s/sesps/a.tv/index.html'
Sep 15 00:21:02 dm900 enigma2[333]: doing version filtering
Sep 15 00:21:02 dm900 enigma2[333]: 0105:  74 00 00 09 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mask:  ff 00 00 3f 00 00
Sep 15 00:21:02 dm900 enigma2[333]: mode:  00 00 00 3e 00 00
Sep 15 00:21:03 dm900 enigma2[333]: sdt update done!
Sep 15 00:21:03 dm900 enigma2[333]: [EPGC] cleanup invalid data
Sep 15 00:21:03 dm900 enigma2[333]: Timeout!
Sep 15 00:21:03 dm900 enigma2[333]: I/  [InfoBar.__restoreState] :: <no detail>
Sep 15 00:21:03 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] ::   1505427663.69
Sep 15 00:21:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:21:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:21:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:21:06 dm900 enigma2[333]: [IDLE] 90.010158062 300 False
Sep 15 00:21:06 dm900 enigma2[333]: nr_read 610 nr_write 816
Sep 15 00:21:06 dm900 enigma2[333]: sum 1426 prev_sum 962
Sep 15 00:21:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:21:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:21:06 dm900 enigma2[333]: [eDVBLocalTimeHandler] Receiver time is 'Fri Sep 15 00:21:06 2017'
Sep 15 00:21:06 dm900 enigma2[333]: [eDVBLocalTimeHandler] Transponder time is 'Fri Sep 15 00:21:06 2017'
Sep 15 00:21:06 dm900 enigma2[333]: [eDVBLocalTimeHandler] diff is 0
Sep 15 00:21:06 dm900 enigma2[333]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Sep 15 00:21:06 dm900 enigma2[333]: [eDVBLocalTimeHandler] not changed
Sep 15 00:21:07 dm900 enigma2[333]: [InfoBar] quickSelectGlobal key:  exit
Sep 15 00:21:07 dm900 enigma2[333]: [InfoBar] exec InfoBar.hide(InfoBar.instance)
Sep 15 00:21:21 dm900 enigma2[333]: no ancillary data in audio stream... abort radiotext pes parser
Sep 15 00:21:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:21:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:21:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:21:36 dm900 enigma2[333]: [IDLE] 119.993030071 300 False
Sep 15 00:21:36 dm900 enigma2[333]: nr_read 611 nr_write 1377
Sep 15 00:21:36 dm900 enigma2[333]: sum 1988 prev_sum 1426
Sep 15 00:21:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:21:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:21:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:22:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:22:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:22:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:22:06 dm900 enigma2[333]: [IDLE] 149.992942095 300 False
Sep 15 00:22:06 dm900 enigma2[333]: nr_read 612 nr_write 1990
Sep 15 00:22:06 dm900 enigma2[333]: sum 2602 prev_sum 1988
Sep 15 00:22:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:22:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:22:29 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:22:28 CEST
Sep 15 00:22:29 dm900 enigma2[333]: [timer.py] next activation: 1505427848 (in 99000 ms)
Sep 15 00:22:30 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:22:29 CEST
Sep 15 00:22:30 dm900 enigma2[333]: [timer.py] next activation: 1505427849 (in 99000 ms)
Sep 15 00:22:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:22:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:22:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:22:36 dm900 enigma2[333]: [IDLE] 179.994074106 300 False
Sep 15 00:22:36 dm900 enigma2[333]: nr_read 613 nr_write 2558
Sep 15 00:22:36 dm900 enigma2[333]: sum 3171 prev_sum 2602
Sep 15 00:22:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:22:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:22:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:22:40 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:22:40 CEST
Sep 15 00:22:40 dm900 enigma2[333]: next real activation is Fr 15 Sep 2017 00:25:00 CEST
Sep 15 00:22:40 dm900 enigma2[333]: [timer.py] next activation: 1505427860 (in 99999 ms)
Sep 15 00:23:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:23:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:23:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:23:06 dm900 enigma2[333]: [IDLE] 209.992938995 300 False
Sep 15 00:23:06 dm900 enigma2[333]: nr_read 614 nr_write 3107
Sep 15 00:23:06 dm900 enigma2[333]: sum 3721 prev_sum 3171
Sep 15 00:23:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:23:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:23:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:23:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:23:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:23:36 dm900 enigma2[333]: [IDLE] 239.992990971 300 False
Sep 15 00:23:36 dm900 enigma2[333]: nr_read 615 nr_write 3669
Sep 15 00:23:36 dm900 enigma2[333]: sum 4284 prev_sum 3721
Sep 15 00:23:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:23:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:23:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:24:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:24:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:24:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:24:06 dm900 enigma2[333]: [IDLE] 269.993183136 300 False
Sep 15 00:24:06 dm900 enigma2[333]: nr_read 615 nr_write 4221
Sep 15 00:24:06 dm900 enigma2[333]: sum 4836 prev_sum 4284
Sep 15 00:24:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:24:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:24:08 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:24:08 CEST
Sep 15 00:24:08 dm900 enigma2[333]: [timer.py] next activation: 1505427948 (in 99998 ms)
Sep 15 00:24:09 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:24:09 CEST
Sep 15 00:24:09 dm900 enigma2[333]: [timer.py] next activation: 1505427949 (in 99999 ms)
Sep 15 00:24:20 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:24:19 CEST
Sep 15 00:24:20 dm900 enigma2[333]: [timer.py] next activation: 1505427900 (in 39999 ms)
Sep 15 00:24:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:24:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:24:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:24:36 dm900 enigma2[333]: [IDLE] 299.995861053 300 False
Sep 15 00:24:36 dm900 enigma2[333]: nr_read 616 nr_write 4760
Sep 15 00:24:36 dm900 enigma2[333]: sum 5376 prev_sum 4836
Sep 15 00:24:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:24:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:24:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:25:00 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:25:00 CEST
Sep 15 00:25:00 dm900 enigma2[333]: [TIMER] activating state 3
Sep 15 00:25:00 dm900 enigma2[333]: [TIMER] stop recording
Sep 15 00:25:00 dm900 enigma2[333]: [AUTOPIN] recording ended 1:0:19:283D:3FB:1:C00000:0:0:0:
Sep 15 00:25:00 dm900 enigma2[333]: stop recording!
Sep 15 00:25:00 dm900 enigma2[333]: [eFileMonitor] 1 events queued
Sep 15 00:25:00 dm900 enigma2[333]: stopping thread.
Sep 15 00:25:00 dm900 enigma2[333]: waiting for aio thread shutdown!
Sep 15 00:25:00 dm900 enigma2[333]: record aio thread has finished
Sep 15 00:25:00 dm900 enigma2[333]: eRecordFileThread (direct io) STOP
Sep 15 00:25:00 dm900 enigma2[333]: thread joined 0
Sep 15 00:25:00 dm900 enigma2[333]: loading streaminfo for /media/hdd/movie/20170915 0020 - Das Erste HD - Hirschhausens Quiz des Menschen.ts
Sep 15 00:25:00 dm900 enigma2[333]: [eMediaScanner::checkWork] scanning /media/hdd/movie/20170915 0020 - Das Erste HD - Hirschhausens Quiz des Menschen.ts for media files
Sep 15 00:25:00 dm900 enigma2[333]: [TIMER] record event 5
Sep 15 00:25:00 dm900 enigma2[333]: [TIMER] record event 1
Sep 15 00:25:00 dm900 enigma2[333]: [remove callback]
Sep 15 00:25:00 dm900 enigma2[333]: [eDVBCAService] free slot 1 demux 0 for service 1:0:19:283D:3FB:1:C00000:0:0:0:
Sep 15 00:25:00 dm900 enigma2[333]: [eDVBCAService] free service 1:0:19:283D:3FB:1:C00000:0:0:0:
Sep 15 00:25:00 dm900 enigma2[333]: [eDVBCAService] remove channel 0x2a60938
Sep 15 00:25:00 dm900 enigma2[333]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok
Sep 15 00:25:00 dm900 enigma2[333]: [eDVBCAService] write leave transponder to '/var/run/ca/ci0.socket' ok
Sep 15 00:25:00 dm900 enigma2[333]: [eDVBLocalTimeHandler] remove channel 0x2a60938
Sep 15 00:25:00 dm900 enigma2[333]: [EPGC] remove channel 0x2a60938
Sep 15 00:25:00 dm900 enigma2[333]: [    0] preClose
Sep 15 00:25:00 dm900 enigma2[333]: next real activation is Fr 15 Sep 2017 00:28:00 CEST
Sep 15 00:25:00 dm900 enigma2[333]: [timer.py] next activation: 1505428000 (in 99799 ms)
Sep 15 00:25:00 dm900 enigma2[333]: [eFileMonitor] File {/tmp/record.1:0:19:283D:3FB:1:C00000:0:0:0:} has been deleted
Sep 15 00:25:00 dm900 enigma2[333]: [eMediaScanner] Transport Stream (mpegts) found...
Sep 15 00:25:00 dm900 enigma2[333]: [eMediaDatabaseThread] Updated 0 datasets!
Sep 15 00:25:03 dm900 enigma2[333]: [    0] dont close frontend until the linked frontend 2 in slot 2 is still in use
Sep 15 00:25:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:25:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:25:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:25:06 dm900 enigma2[333]: [IDLE] 329.990118027 300 False
Sep 15 00:25:06 dm900 enigma2[333]: [ePopen] command: ('sdparm', 'sdparm', '--command=stop', '/dev/sdb')
Sep 15 00:25:06 dm900 enigma2[333]: child has terminated
Sep 15 00:25:06 dm900 enigma2[333]: pipes closed
Sep 15 00:25:06 dm900 enigma2[333]: poll: unhandled POLLERR/HUP/NVAL for fd 240(16)
Sep 15 00:25:06 dm900 enigma2[333]: Harddisk.py: failed to read /sys/devices/rdb.4/f0471000.xhci_v2/usb2/2-2/2-2:1.0/host2/target2:0:0/2:0:0:0/block/sdb/partition
Sep 15 00:25:06 dm900 enigma2[333]: found block device 'sdb':
Sep 15 00:25:06 dm900 enigma2[333]: found block device 'sdb1':
Sep 15 00:25:06 dm900 enigma2[333]: nr_read 733 nr_write 5310
Sep 15 00:25:06 dm900 enigma2[333]: sum 6043 prev_sum 5376
Sep 15 00:25:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:25:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:25:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:25:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:25:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:25:36 dm900 enigma2[333]: [IDLE] 359.990453005 300 True
Sep 15 00:25:36 dm900 enigma2[333]: nr_read 734 nr_write 5636
Sep 15 00:25:36 dm900 enigma2[333]: sum 6370 prev_sum 6043
Sep 15 00:25:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:25:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:25:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:25:48 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:25:47 CEST
Sep 15 00:25:48 dm900 enigma2[333]: [timer.py] next activation: 1505428048 (in 99999 ms)
Sep 15 00:25:49 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:25:49 CEST
Sep 15 00:25:49 dm900 enigma2[333]: [timer.py] next activation: 1505428049 (in 99998 ms)
Sep 15 00:26:03 dm900 enigma2[333]: [    0] dont close frontend until the linked frontend 2 in slot 2 is still in use
Sep 15 00:26:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:26:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:26:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:26:06 dm900 enigma2[333]: [IDLE] 389.990597963 300 True
Sep 15 00:26:06 dm900 enigma2[333]: nr_read 734 nr_write 5950
Sep 15 00:26:06 dm900 enigma2[333]: sum 6684 prev_sum 6370
Sep 15 00:26:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:26:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:26:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:26:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:26:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:26:36 dm900 enigma2[333]: [IDLE] 419.990651131 300 True
Sep 15 00:26:36 dm900 enigma2[333]: nr_read 734 nr_write 6264
Sep 15 00:26:36 dm900 enigma2[333]: sum 6998 prev_sum 6684
Sep 15 00:26:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:26:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:26:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:26:40 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:26:39 CEST
Sep 15 00:26:40 dm900 enigma2[333]: [timer.py] next activation: 1505428080 (in 79999 ms)
Sep 15 00:27:03 dm900 enigma2[333]: [    0] dont close frontend until the linked frontend 2 in slot 2 is still in use
Sep 15 00:27:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:27:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:27:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:27:06 dm900 enigma2[333]: [IDLE] 449.991064072 300 True
Sep 15 00:27:06 dm900 enigma2[333]: nr_read 736 nr_write 6584
Sep 15 00:27:06 dm900 enigma2[333]: sum 7320 prev_sum 6998
Sep 15 00:27:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:27:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:27:27 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:27:27 CEST
Sep 15 00:27:28 dm900 enigma2[333]: [timer.py] next activation: 1505428147 (in 99000 ms)
Sep 15 00:27:29 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:27:29 CEST
Sep 15 00:27:29 dm900 enigma2[333]: [timer.py] next activation: 1505428149 (in 99998 ms)
Sep 15 00:27:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:27:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:27:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:27:36 dm900 enigma2[333]: [IDLE] 479.990605116 300 True
Sep 15 00:27:36 dm900 enigma2[333]: nr_read 736 nr_write 6902
Sep 15 00:27:36 dm900 enigma2[333]: sum 7638 prev_sum 7320
Sep 15 00:27:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:27:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:27:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:28:00 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:28:00 CEST
Sep 15 00:28:00 dm900 enigma2[333]: [TIMER] activating state 3
Sep 15 00:28:00 dm900 enigma2[333]: [TIMER] stop recording
Sep 15 00:28:00 dm900 enigma2[333]: [AUTOPIN] recording ended 1:0:19:2B66:3F3:1:C00000:0:0:0:
Sep 15 00:28:00 dm900 enigma2[333]: [eFileMonitor] 1 events queued
Sep 15 00:28:00 dm900 enigma2[333]: stop recording!
Sep 15 00:28:00 dm900 enigma2[333]: stopping thread.
Sep 15 00:28:00 dm900 enigma2[333]: waiting for aio thread shutdown!
Sep 15 00:28:00 dm900 enigma2[333]: record aio thread has finished
Sep 15 00:28:00 dm900 enigma2[333]: eRecordFileThread (direct io) STOP
Sep 15 00:28:00 dm900 enigma2[333]: thread joined 0
Sep 15 00:28:00 dm900 enigma2[333]: loading streaminfo for /media/hdd/movie/20170915 0021 - ZDF HD - heute+.ts
Sep 15 00:28:00 dm900 enigma2[333]: [eMediaScanner::checkWork] scanning /media/hdd/movie/20170915 0021 - ZDF HD - heute+.ts for media files
Sep 15 00:28:00 dm900 enigma2[333]: [TIMER] record event 5
Sep 15 00:28:00 dm900 enigma2[333]: [TIMER] record event 1
Sep 15 00:28:00 dm900 enigma2[333]: [remove callback]
Sep 15 00:28:00 dm900 enigma2[333]: [eDVBCAService] free slot 0 demux 1 for service 1:0:19:2B66:3F3:1:C00000:0:0:0:
Sep 15 00:28:00 dm900 enigma2[333]: [eDVBCAService] free service 1:0:19:2B66:3F3:1:C00000:0:0:0:
Sep 15 00:28:00 dm900 enigma2[333]: [eDVBCAService] remove channel 0x2b573e8
Sep 15 00:28:00 dm900 enigma2[333]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok
Sep 15 00:28:00 dm900 enigma2[333]: [eDVBCAService] write leave transponder to '/var/run/ca/ci0.socket' ok
Sep 15 00:28:00 dm900 enigma2[333]: [eDVBLocalTimeHandler] remove channel 0x2b573e8
Sep 15 00:28:00 dm900 enigma2[333]: [EPGC] remove channel 0x2b573e8
Sep 15 00:28:00 dm900 enigma2[333]: [    1] preClose
Sep 15 00:28:00 dm900 enigma2[333]: next real activation is Fr 15 Sep 2017 10:24:40 CEST
Sep 15 00:28:00 dm900 enigma2[333]: [timer.py] next activation: 1505428180 (in 99802 ms)
Sep 15 00:28:00 dm900 enigma2[333]: [eFileMonitor] File {/tmp/record.1:0:19:2B66:3F3:1:C00000:0:0:0:} has been deleted
Sep 15 00:28:00 dm900 enigma2[333]: [eMediaScanner] Transport Stream (mpegts) found...
Sep 15 00:28:00 dm900 enigma2[333]: [eMediaDatabaseThread] Updated 0 datasets!
Sep 15 00:28:03 dm900 enigma2[333]: [    1] prepareClose running loop
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-1] lock 0
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-1] locked 0
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-1] delayed close frontend
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-1] unlock 0
Sep 15 00:28:03 dm900 enigma2[333]: [    1] close frontend
Sep 15 00:28:03 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-0] sendDiseqc: e01002 (DiSEqC peripherial power off)
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-0] sleep 150ms
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:28:03 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:28:03 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:28:03 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:03 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:03 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:04 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:04 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:04 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:04 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:28:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:28:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:28:06 dm900 enigma2[333]: [IDLE] 509.990061998 300 True
Sep 15 00:28:06 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:06 dm900 enigma2[333]: nr_read 902 nr_write 7161
Sep 15 00:28:06 dm900 enigma2[333]: sum 8063 prev_sum 7638
Sep 15 00:28:06 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:28:06 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:28:07 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:08 dm900 enigma2[333]: [InfoBar] quickSelectGlobal key:  ok
Sep 15 00:28:08 dm900 enigma2[333]: [InfoBar] exec InfoBar.toggleShow(InfoBar.instance)
Sep 15 00:28:08 dm900 enigma2[333]: create buffer for widget 1280 x 200
Sep 15 00:28:08 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:08 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:28:08 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:28:08 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:28:08 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:28:08 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:28:08 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:28:08 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:28:08 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:28:08 dm900 enigma2[333]: [    0] preClose
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:28:08 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:28:08 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:28:08 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:28:08 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:28:08 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:08 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:28:08 dm900 enigma2[333]: stop release channel timer
Sep 15 00:28:09 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:28:11 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:28:11 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:28:11 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:28:11 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:28:11 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:28:11 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:28:11 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:28:12 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:12 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:28:13 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:13 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:13 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:14 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:14 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:15 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:15 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:17 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:18 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:19 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:28:19 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:28:19 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:28:19 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:28:19 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:28:19 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:28:19 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:28:19 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:28:19 dm900 enigma2[333]: [    0] preClose
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:28:19 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:28:19 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:28:19 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:28:19 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:19 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:28:19 dm900 enigma2[333]: stop release channel timer
Sep 15 00:28:19 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:28:22 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:28:22 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:28:22 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:28:22 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:28:22 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:28:22 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:28:22 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:28:22 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:22 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:28:22 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:22 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:23 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:23 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:24 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:24 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:25 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:27 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:27 dm900 enigma2[333]: [InfoBar] quickSelectGlobal key:  ok
Sep 15 00:28:27 dm900 enigma2[333]: [InfoBar] exec InfoBar.toggleShow(InfoBar.instance)
Sep 15 00:28:27 dm900 enigma2[333]: create buffer for widget 1280 x 200
Sep 15 00:28:28 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:29 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:28:29 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:28:29 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:28:29 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:28:29 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:28:29 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:28:29 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:28:29 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:28:29 dm900 enigma2[333]: [    0] preClose
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:28:29 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:28:29 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:28:29 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:28:29 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:29 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:28:29 dm900 enigma2[333]: stop release channel timer
Sep 15 00:28:29 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:28:32 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:28:32 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:28:32 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:28:32 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:28:32 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:28:32 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:28:32 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:28:32 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:32 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:28:32 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:32 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:33 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:34 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:34 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:34 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:35 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:35 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:28:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:28:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:28:36 dm900 enigma2[333]: [IDLE] 539.990682125 300 True
Sep 15 00:28:36 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:36 dm900 enigma2[333]: nr_read 902 nr_write 7173
Sep 15 00:28:36 dm900 enigma2[333]: sum 8075 prev_sum 8063
Sep 15 00:28:36 dm900 enigma2[333]: hdd was accessed since previous check!
Sep 15 00:28:36 dm900 enigma2[333]: [IDLE] 0 300 False
Sep 15 00:28:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:28:39 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:39 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:28:39 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:28:39 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:28:39 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:28:39 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:28:39 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:28:39 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:28:39 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:28:39 dm900 enigma2[333]: [    0] preClose
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:28:39 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:28:39 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:28:39 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:28:39 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:39 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:28:39 dm900 enigma2[333]: stop release channel timer
Sep 15 00:28:39 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:28:42 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:28:42 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:28:42 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:28:42 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:28:42 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:28:42 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:28:42 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:28:42 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:42 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:28:43 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:28:43 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:28:43 dm900 enigma2[333]: OURSTATE: failed
Sep 15 00:28:43 dm900 enigma2[333]: tune failed.
Sep 15 00:28:43 dm900 enigma2[333]: DVB service failed to tune - error 1
Sep 15 00:28:43 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: tuning... Tunen fehlgeschlagen! 1505428123.37
Sep 15 00:28:43 dm900 enigma2[333]: create buffer for widget 600 x 140
Sep 15 00:28:43 dm900 enigma2[333]: sdt update done!
Sep 15 00:28:44 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:44 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:44 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:44 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:46 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:46 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:49 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:49 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:28:49 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:28:49 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:28:49 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:28:49 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:28:49 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:28:49 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:28:49 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:28:49 dm900 enigma2[333]: [    0] preClose
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:28:49 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:28:49 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:28:49 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:28:50 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:28:50 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:50 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:28:50 dm900 enigma2[333]: stop release channel timer
Sep 15 00:28:50 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:28:52 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:28:52 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:28:52 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:28:52 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:28:52 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:28:52 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:28:52 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:28:53 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:28:53 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:28:53 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:28:53 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:28:53 dm900 enigma2[333]: OURSTATE: failed
Sep 15 00:28:53 dm900 enigma2[333]: tune failed.
Sep 15 00:28:53 dm900 enigma2[333]: DVB service failed to tune - error 1
Sep 15 00:28:53 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505428133.61
Sep 15 00:28:53 dm900 enigma2[333]: sdt update done!
Sep 15 00:28:53 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:53 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:54 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:55 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:55 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:28:56 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:57 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:28:59 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:00 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:29:00 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:29:00 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:29:00 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:29:00 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:29:00 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:29:00 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:29:00 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:29:00 dm900 enigma2[333]: [    0] preClose
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:29:00 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:29:00 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:29:00 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:29:00 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:00 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:29:00 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:29:00 dm900 enigma2[333]: stop release channel timer
Sep 15 00:29:00 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:29:03 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:29:03 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:29:03 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:29:03 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:29:03 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:29:03 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:29:03 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:29:04 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:29:04 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:29:04 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:04 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:04 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:04 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:04 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:05 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:29:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:29:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:29:06 dm900 enigma2[333]: [IDLE] 569.991052151 300 True
Sep 15 00:29:06 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:06 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:06 dm900 enigma2[333]: nr_read 902 nr_write 7173
Sep 15 00:29:06 dm900 enigma2[333]: sum 8075 prev_sum 8075
Sep 15 00:29:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:29:06 dm900 enigma2[333]: [IDLE] 29.999767065 300 False
Sep 15 00:29:07 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:29:07 CEST
Sep 15 00:29:07 dm900 enigma2[333]: [timer.py] next activation: 1505428247 (in 99998 ms)
Sep 15 00:29:07 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:08 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:09 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:29:09 CEST
Sep 15 00:29:09 dm900 enigma2[333]: [timer.py] next activation: 1505428249 (in 99998 ms)
Sep 15 00:29:09 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:10 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:11 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:29:11 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:29:11 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:29:11 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:29:11 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:29:11 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:29:11 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:29:11 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:29:11 dm900 enigma2[333]: [    0] preClose
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:29:11 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:29:11 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:29:11 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:29:11 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:29:11 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:11 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:29:11 dm900 enigma2[333]: stop release channel timer
Sep 15 00:29:12 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:29:14 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:29:14 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:29:14 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:29:14 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:29:14 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:29:14 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:29:14 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:29:14 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:14 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:15 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:29:15 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:29:15 dm900 enigma2[333]: OURSTATE: failed
Sep 15 00:29:15 dm900 enigma2[333]: tune failed.
Sep 15 00:29:15 dm900 enigma2[333]: DVB service failed to tune - error 1
Sep 15 00:29:15 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505428155.41
Sep 15 00:29:15 dm900 enigma2[333]: sdt update done!
Sep 15 00:29:15 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:15 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:16 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:16 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:17 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:17 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:18 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:19 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:20 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:20 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:22 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:29:22 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:29:22 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:29:22 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:29:22 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:29:22 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:29:22 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:29:22 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:29:22 dm900 enigma2[333]: [    0] preClose
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:29:22 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:29:22 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:29:22 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:29:23 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:29:23 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:23 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:29:23 dm900 enigma2[333]: stop release channel timer
Sep 15 00:29:23 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:29:25 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:29:25 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:29:25 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:29:25 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:29:25 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:29:25 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:29:25 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:29:26 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:26 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:26 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:29:26 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:29:26 dm900 enigma2[333]: OURSTATE: failed
Sep 15 00:29:26 dm900 enigma2[333]: tune failed.
Sep 15 00:29:26 dm900 enigma2[333]: DVB service failed to tune - error 1
Sep 15 00:29:26 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505428166.63
Sep 15 00:29:26 dm900 enigma2[333]: sdt update done!
Sep 15 00:29:26 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:26 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:27 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:27 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:28 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:28 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:29 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:30 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:31 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:32 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:33 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:29:33 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:29:33 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:29:33 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:29:33 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:29:33 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:29:33 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:29:33 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:29:33 dm900 enigma2[333]: [    0] preClose
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:29:33 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:29:33 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:29:33 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:29:33 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:29:33 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:33 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:29:33 dm900 enigma2[333]: stop release channel timer
Sep 15 00:29:34 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:29:36 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:29:36 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:29:36 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:29:36 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:29:36 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:29:36 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:29:36 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:29:36 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:29:36 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:29:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:29:36 dm900 enigma2[333]: [IDLE] 599.98993206 300 True
Sep 15 00:29:36 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:36 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:36 dm900 enigma2[333]: nr_read 902 nr_write 7173
Sep 15 00:29:36 dm900 enigma2[333]: sum 8075 prev_sum 8075
Sep 15 00:29:36 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:29:36 dm900 enigma2[333]: [IDLE] 59.9994490147 300 False
Sep 15 00:29:37 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:29:37 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:29:37 dm900 enigma2[333]: OURSTATE: failed
Sep 15 00:29:37 dm900 enigma2[333]: tune failed.
Sep 15 00:29:37 dm900 enigma2[333]: DVB service failed to tune - error 1
Sep 15 00:29:37 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505428177.2
Sep 15 00:29:37 dm900 enigma2[333]: sdt update done!
Sep 15 00:29:37 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:37 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:38 dm900 enigma2[333]: [EPGC] cleanupOutdated
Sep 15 00:29:39 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:39 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:39 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:40 dm900 enigma2[333]: It's now  Fr 15 Sep 2017 00:29:39 CEST
Sep 15 00:29:40 dm900 enigma2[333]: next real activation is Fr 15 Sep 2017 10:24:40 CEST
Sep 15 00:29:40 dm900 enigma2[333]: [timer.py] next activation: 1505428279 (in 98999 ms)
Sep 15 00:29:40 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:41 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:42 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:43 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:44 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:29:44 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:29:44 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:29:44 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:29:44 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:29:44 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:29:44 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:29:44 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:29:44 dm900 enigma2[333]: [    0] preClose
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:29:44 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:29:44 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:29:44 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:29:44 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:44 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:29:44 dm900 enigma2[333]: stop release channel timer
Sep 15 00:29:44 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:29:47 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:29:47 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:29:47 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:29:47 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:29:47 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:29:47 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:29:47 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:29:47 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:47 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:47 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:29:47 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:29:47 dm900 enigma2[333]: OURSTATE: failed
Sep 15 00:29:47 dm900 enigma2[333]: tune failed.
Sep 15 00:29:47 dm900 enigma2[333]: DVB service failed to tune - error 1
Sep 15 00:29:47 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505428187.76
Sep 15 00:29:47 dm900 enigma2[333]: sdt update done!
Sep 15 00:29:48 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:48 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:48 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:49 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:49 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:49 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:50 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:50 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:51 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:53 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:54 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:54 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:29:54 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:29:54 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:29:54 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:29:54 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:29:54 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:29:54 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:29:54 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:29:54 dm900 enigma2[333]: [    0] preClose
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:29:54 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:29:54 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:29:54 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:29:54 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:29:54 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:54 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:29:54 dm900 enigma2[333]: stop release channel timer
Sep 15 00:29:55 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:29:57 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:29:57 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:29:57 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:29:57 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:29:57 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:29:57 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:29:57 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:29:57 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:29:57 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:29:58 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:29:58 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:29:58 dm900 enigma2[333]: OURSTATE: failed
Sep 15 00:29:58 dm900 enigma2[333]: tune failed.
Sep 15 00:29:58 dm900 enigma2[333]: DVB service failed to tune - error 1
Sep 15 00:29:58 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505428198.33
Sep 15 00:29:58 dm900 enigma2[333]: sdt update done!
Sep 15 00:29:58 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:29:58 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:29:59 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:30:00 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:30:00 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:30:01 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:30:02 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:30:04 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:30:05 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:30:05 dm900 enigma2[333]: [    2] stateLostLock
Sep 15 00:30:05 dm900 enigma2[333]: OURSTATE: lost lock, trying to retune
Sep 15 00:30:05 dm900 enigma2[333]: [    2] tune type 1
Sep 15 00:30:05 dm900 enigma2[333]: RotorCmd ffffffff, lastRotorCmd ffffffff
Sep 15 00:30:05 dm900 enigma2[333]: [    2] prepare_sat System DVB-S Freq 11523250H SR 22000000 INV Auto FEC 5/6 orbpos 192 modulation QPSK pilot Auto, rolloff 0.35
Sep 15 00:30:05 dm900 enigma2[333]: [    2] tuning to 1773 mhz
Sep 15 00:30:05 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] update oparm
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] lock 0
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] sleep 50ms
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] sec fe 0 is closed... open it...
Sep 15 00:30:05 dm900 enigma2[333]: [    0] opening frontend
Sep 15 00:30:05 dm900 enigma2[333]: [    0] preClose
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] lock 4
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] locked 0
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-0] set static current limiting
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] invalidate current switch params
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-0] setVoltage 18V
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] sleep 10ms
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] update current switch params
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] startTuneTimeout 5000
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] setFrontend 1
Sep 15 00:30:05 dm900 enigma2[333]: [    2] setting frontend
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] unlock 0
Sep 15 00:30:05 dm900 enigma2[333]: [SEC-2] sleep 500ms
Sep 15 00:30:05 dm900 enigma2[333]: [    2] fe event: status 0, freq 1773250, inversion off, m_tuning 1 F
Sep 15 00:30:05 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 1 N
Sep 15 00:30:06 dm900 enigma2[333]: [    2] fe event: status 1f, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:30:06 dm900 enigma2[333]: OURSTATE: ok
Sep 15 00:30:06 dm900 enigma2[333]: stop release channel timer
Sep 15 00:30:06 dm900 enigma2[333]: -+ 1/2 TID 4e
Sep 15 00:30:06 dm900 enigma2[333]: [SEC-0] set dynamic current limiting
Sep 15 00:30:06 dm900 enigma2[333]: nr_read 226 nr_write 1
Sep 15 00:30:06 dm900 enigma2[333]: sum 227 prev_sum 227
Sep 15 00:30:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:30:06 dm900 enigma2[333]: [IDLE] 629.991121054 300 True
Sep 15 00:30:06 dm900 enigma2[333]: nr_read 902 nr_write 7173
Sep 15 00:30:06 dm900 enigma2[333]: sum 8075 prev_sum 8075
Sep 15 00:30:06 dm900 enigma2[333]: hdd IDLE!
Sep 15 00:30:06 dm900 enigma2[333]: [IDLE] 89.9996979237 300 False
Sep 15 00:30:07 dm900 enigma2[333]: ++ 2/2 TID 4e
Sep 15 00:30:07 dm900 enigma2[333]: done!
Sep 15 00:30:07 dm900 enigma2[333]: W/  [InfoBar.__servicePlaying] :: <no detail>
Sep 15 00:30:07 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505428207.18
Sep 15 00:30:07 dm900 enigma2[333]: doing version filtering
Sep 15 00:30:07 dm900 enigma2[333]: 0012:  4e 11 f8 25 00 00
Sep 15 00:30:07 dm900 enigma2[333]: mask:  ff ff ff 3f 00 00
Sep 15 00:30:07 dm900 enigma2[333]: mode:  00 00 00 3e 00 00
Sep 15 00:30:08 dm900 enigma2[333]: [    0] prepareClose running loop
Sep 15 00:30:08 dm900 enigma2[333]: [SEC-0] lock 0
Sep 15 00:30:08 dm900 enigma2[333]: [SEC-0] locked 0
Sep 15 00:30:08 dm900 enigma2[333]: [SEC-0] delayed close frontend
Sep 15 00:30:08 dm900 enigma2[333]: [SEC-0] unlock 0
Sep 15 00:30:08 dm900 enigma2[333]: [    0] close frontend
Sep 15 00:30:08 dm900 enigma2[333]: [SEC-0] setVoltage 0V
Sep 15 00:30:08 dm900 enigma2[333]: [    2] fe event: status 1, freq 1773250, inversion off, m_tuning 2 N
Sep 15 00:30:08 dm900 enigma2[333]: OURSTATE: tuning
Sep 15 00:30:09 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 3 N
Sep 15 00:30:09 dm900 enigma2[333]: FE_TIMEDOUT! ..abort
Sep 15 00:30:09 dm900 enigma2[333]: OURSTATE: failed
Sep 15 00:30:09 dm900 enigma2[333]: tune failed.
Sep 15 00:30:09 dm900 enigma2[333]: DVB service failed to tune - error 1
Sep 15 00:30:09 dm900 enigma2[333]: I/  [InfoBar.setPlaybackState] :: None None 1505428209.54
Sep 15 00:30:09 dm900 enigma2[333]: sdt update done!
Sep 15 00:30:09 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:30:09 dm900 enigma2[333]: [    2] lost lock
Sep 15 00:30:10 dm900 enigma2[333]: [    2] fe event: status 20, freq 1773250, inversion off, m_tuning 0 N
Sep 15 00:30:10 dm900 enigma2[333]: [    2] fe event: status 21, freq 1773250, inversion off, m_tuning 0 N