***************************** * * * The Gemini Plugin 3.3 * * * ***************************** opendreambox 2.5.0 dm900 dm900 login: root root@dm900:~# journalctl -fu enigma2 -- Logs begin at Thu 1970-01-01 01:00:00 CET. -- Apr 18 19:58:16 dm900 enigma2[241]: [TrackAutoselector]:selectSubtitles now sorted by codec format [] Apr 18 19:58:16 dm900 enigma2[241]: [TrackAutoselector]:selectSubtitles now sorted by language [] Apr 18 19:58:16 dm900 enigma2[241]: [TrackAutoselector]:selectSubtitles now sorted by forced flag [] Apr 18 19:58:16 dm900 enigma2[241]: [TrackAutoselector]:selectSubtitles now sorted by saved flag [] Apr 18 19:58:16 dm900 enigma2[241]: [TrackAutoselector]:selectSubtitles resorted list of subtitle streams: [] playing_idx: None Apr 18 19:58:16 dm900 enigma2[241]: [TrackAutoselector]:selectSubtitles enable if saved (by dvb service / media database ) 0 Apr 18 19:58:16 dm900 enigma2[241]: [TrackAutoselector]:selectSubtitles enable if forced flag set 0 Apr 18 19:58:18 dm900 enigma2[241]: [EPGC] cleanupOutdated Apr 18 19:58:26 dm900 enigma2[241]: [DLNA] MediaRenderer detected: 55POS9002/12 (MediaRenderer, uuid:F00DBABE-AA5E-BABA- DADA-121EE3A84C01) Apr 18 19:58:26 dm900 enigma2[241]: [DLNA] MediaServer Detected: Denon AVR-X4400H (MediaServer) Apr 18 19:58:29 dm900 enigma2[241]: [eCec] handle message 0 -> 3 : 8f Apr 18 19:58:29 dm900 enigma2[241]: [eCec] Give Device Power Status Apr 18 19:58:29 dm900 enigma2[241]: [eCec] send 3 -> 0 : 90 00 Apr 18 19:58:34 dm900 enigma2[241]: [InfoBar] quickSelectGlobal key: channelup Apr 18 19:58:34 dm900 enigma2[241]: [InfoBar] exec from Screens.InfoBar import InfoBar Apr 18 19:58:34 dm900 enigma2[241]: if InfoBar and InfoBar.instance: Apr 18 19:58:34 dm900 enigma2[241]: InfoBar.openServiceList(InfoBar.instance) Apr 18 19:58:34 dm900 enigma2[241]: allocating new converter! Apr 18 19:58:34 dm900 enigma2[241]: allocating new converter! Apr 18 19:58:34 dm900 enigma2[241]: allocating new converter! Apr 18 19:58:34 dm900 enigma2[241]: reused converter! Apr 18 19:58:34 dm900 enigma2[241]: allocating new converter! Apr 18 19:58:34 dm900 enigma2[241]: allocating new converter! Apr 18 19:58:34 dm900 enigma2[241]: action -> ChannelSelectBaseActions nextBouquet Apr 18 19:58:35 dm900 enigma2[241]: action -> ChannelSelectBaseActions nextBouquet Apr 18 19:58:35 dm900 enigma2[241]: It's now Mi 18 Apr 2018 19:58:35 CEST Apr 18 19:58:35 dm900 enigma2[241]: [timer.py] next activation: 1524074415 (in 99022 ms) Apr 18 19:58:36 dm900 enigma2[241]: It's now Mi 18 Apr 2018 19:58:36 CEST Apr 18 19:58:36 dm900 enigma2[241]: [timer.py] next activation: 1524074416 (in 99023 ms) Apr 18 19:58:37 dm900 enigma2[241]: action -> ChannelSelectBaseActions nextBouquet Apr 18 19:58:37 dm900 enigma2[241]: [DLNA] MediaServer Detected: Denon AVR-X4400H (MediaServer) Apr 18 19:58:38 dm900 enigma2[241]: action -> ChannelSelectBaseActions selectServiceDown Apr 18 19:58:38 dm900 enigma2[241]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap? Apr 18 19:58:38 dm900 enigma2[241]: action -> WizardActions down Apr 18 19:58:38 dm900 enigma2[241]: unknown action WizardActions/down! typo in keymap? Apr 18 19:58:38 dm900 enigma2[241]: action -> ChannelSelectBaseActions nextBouquet Apr 18 19:58:39 dm900 enigma2[241]: action -> ChannelSelectBaseActions nextBouquet Apr 18 19:58:40 dm900 enigma2[241]: action -> ChannelSelectBaseActions nextBouquet Apr 18 19:58:40 dm900 enigma2[241]: action -> ChannelSelectBaseActions nextBouquet Apr 18 19:58:40 dm900 enigma2[241]: action -> ChannelSelectBaseActions nextBouquet Apr 18 19:58:41 dm900 enigma2[241]: action -> ChannelSelectBaseActions selectServiceDown Apr 18 19:58:41 dm900 enigma2[241]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap? Apr 18 19:58:41 dm900 enigma2[241]: action -> WizardActions down Apr 18 19:58:41 dm900 enigma2[241]: unknown action WizardActions/down! typo in keymap? Apr 18 19:58:42 dm900 enigma2[241]: action -> ChannelSelectBaseActions selectServiceDown Apr 18 19:58:42 dm900 enigma2[241]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap? Apr 18 19:58:42 dm900 enigma2[241]: action -> WizardActions down Apr 18 19:58:42 dm900 enigma2[241]: unknown action WizardActions/down! typo in keymap? Apr 18 19:58:42 dm900 enigma2[241]: action -> ChannelSelectBaseActions selectServiceDown Apr 18 19:58:42 dm900 enigma2[241]: unknown action ChannelSelectBaseActions/selectServiceDown! typo in keymap? Apr 18 19:58:42 dm900 enigma2[241]: action -> WizardActions down Apr 18 19:58:42 dm900 enigma2[241]: unknown action WizardActions/down! typo in keymap? Apr 18 19:58:42 dm900 enigma2[241]: action -> OkCancelActions ok Apr 18 19:58:42 dm900 enigma2[241]: playing 1:0:19:6B:C:85:C00000:0:0:0:http%3a//root%3adreambox@192.168.1.16%3a8001/1%3 a256%3a19%3a6B%3aC%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Alien HD (DM920UHD) Apr 18 19:58:42 dm900 enigma2[241]: stopping thread. Apr 18 19:58:42 dm900 enigma2[241]: waiting for aio thread shutdown! Apr 18 19:58:42 dm900 enigma2[241]: record aio thread has finished Apr 18 19:58:42 dm900 enigma2[241]: eRecordFileThread (direct io) STOP Apr 18 19:58:42 dm900 enigma2[241]: thread joined 0 Apr 18 19:58:42 dm900 enigma2[241]: remove timeshift file Apr 18 19:58:42 dm900 enigma2[241]: [eDVBCAService] free slot 0 demux 0 for service 1:0:19:EF75:3F9:1:C00000:0:0:0: Apr 18 19:58:42 dm900 enigma2[241]: [eDVBCAService] free service 1:0:19:EF75:3F9:1:C00000:0:0:0: Apr 18 19:58:42 dm900 enigma2[241]: [eDBusInterface::Private::sendEvent 0 Apr 18 19:58:42 dm900 enigma2[241]: [eFileMonitor] 1 events queued Apr 18 19:58:42 dm900 enigma2[241]: [TeleText] service stopped Apr 18 19:58:42 dm900 enigma2[241]: [TeleText] send array('B', [1, 0, 0, 0]) Apr 18 19:58:42 dm900 enigma2[241]: disable dvb subtitles Apr 18 19:58:42 dm900 enigma2[241]: disable teletext subtitles page ffffffffffffffff (und) Apr 18 19:58:42 dm900 enigma2[241]: decoder state: play, vpid=-1, apid=-1 Apr 18 19:58:42 dm900 enigma2[241]: DEMUX_STOP - pcr - ok Apr 18 19:58:42 dm900 enigma2[241]: DEMUX_STOP - video - ok Apr 18 19:58:42 dm900 enigma2[241]: VIDEO_STOP - ok Apr 18 19:58:42 dm900 enigma2[241]: AUDIO_STOP - ok Apr 18 19:58:42 dm900 enigma2[241]: DEMUX_STOP - audio - ok Apr 18 19:58:42 dm900 enigma2[241]: AUDIO_CONTINUE - ok Apr 18 19:58:42 dm900 enigma2[241]: DEMUX_STOP - ttx - ok Apr 18 19:58:42 dm900 enigma2[241]: start release channel timer Apr 18 19:58:42 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: connecting... Please wait! 1524074322.98 Apr 18 19:58:42 dm900 enigma2[241]: [TeleText] service started Apr 18 19:58:42 dm900 enigma2[241]: not pauseable. Apr 18 19:58:42 dm900 enigma2[241]: alloc PVR Apr 18 19:58:42 dm900 enigma2[241]: allocate channel.. 000c:0085:00c00000 (http://root:dreambox@192.168.1.16:8001/1:256: 19:6B:C:85:C00000:0:0:0:) Apr 18 19:58:42 dm900 enigma2[241]: [eDVBCAService] new channel 0x2439c70! Apr 18 19:58:42 dm900 enigma2[241]: eTsRemoteSource::close() Apr 18 19:58:42 dm900 enigma2[241]: [eTsRemoteSource] souphttpsrc user-agent='GStreamer souphttpsrc libsoup/2.52.2;' Apr 18 19:58:42 dm900 enigma2[241]: allocate pvr demux Apr 18 19:58:42 dm900 enigma2[241]: have 1 video stream(s) (02ff), and 1 audio stream(s) (0303), and the pcr pid is 02ff , and the text pid is ffffffff Apr 18 19:58:42 dm900 enigma2[241]: allocate pvr demux Apr 18 19:58:42 dm900 enigma2[241]: disable teletext subtitles page ffffffffffffffff (und) Apr 18 19:58:42 dm900 enigma2[241]: PES filter: DMX_SET_PES_FILTER - Invalid argument Apr 18 19:58:42 dm900 enigma2[241]: decoder state: play, vpid=767, apid=771 Apr 18 19:58:42 dm900 enigma2[241]: DMX_SET_PES_FILTER(0x303) - audio - ok Apr 18 19:58:42 dm900 enigma2[241]: DEMUX_START - audio - ok Apr 18 19:58:42 dm900 enigma2[241]: AUDIO_SET_BYPASS(0) - ok Apr 18 19:58:42 dm900 enigma2[241]: AUDIO_PAUSE - ok Apr 18 19:58:42 dm900 enigma2[241]: AUDIO_PLAY - ok Apr 18 19:58:42 dm900 enigma2[241]: Video Device: /dev/dvb/adapter0/video0 Apr 18 19:58:42 dm900 enigma2[241]: demux device: /dev/dvb/adapter0/demux7 Apr 18 19:58:43 dm900 enigma2[241]: VIDEO_SET_STREAMTYPE 1 - ok Apr 18 19:58:43 dm900 enigma2[241]: DMX_SET_PES_FILTER(0x2ff) - video - ok Apr 18 19:58:43 dm900 enigma2[241]: DEMUX_START - video - ok Apr 18 19:58:43 dm900 enigma2[241]: VIDEO_FREEZE - ok Apr 18 19:58:43 dm900 enigma2[241]: VIDEO_PLAY - ok Apr 18 19:58:43 dm900 enigma2[241]: VIDEO_SLOWMOTION(0) - ok Apr 18 19:58:43 dm900 enigma2[241]: VIDEO_FAST_FORWARD(0) - ok Apr 18 19:58:43 dm900 enigma2[241]: VIDEO_CONTINUE - ok Apr 18 19:58:43 dm900 enigma2[241]: AUDIO_CONTINUE - ok Apr 18 19:58:43 dm900 enigma2[241]: W/ [InfoBar.__servicePlaying] :: Apr 18 19:58:43 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: None None 1524074323.0 Apr 18 19:58:43 dm900 enigma2[241]: not pauseable. Apr 18 19:58:43 dm900 enigma2[241]: loading streaminfo for http://root:dreambox@192.168.1.16:8001/1:256:19:6B:C:85:C0000 0:0:0:0: Apr 18 19:58:43 dm900 enigma2[241]: cutfile not found! Apr 18 19:58:43 dm900 enigma2[241]: no cue sheet Apr 18 19:58:43 dm900 enigma2[241]: before: 1 Apr 18 19:58:43 dm900 enigma2[241]: after: 1 Apr 18 19:58:43 dm900 enigma2[241]: setIoPrio best-effort level 5 ok Apr 18 19:58:43 dm900 enigma2[241]: FILEPUSH THREAD START Apr 18 19:58:43 dm900 enigma2[241]: [eDVBCAService] channel 0x2439c70 running Apr 18 19:58:43 dm900 enigma2[241]: [eDVBLocalTimeHandler] channel 0x2439c70 running Apr 18 19:58:43 dm900 enigma2[241]: no version filtering Apr 18 19:58:43 dm900 enigma2[241]: 0014: 70 00 00 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: mask: fc 00 00 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: [EPGC] channel 0x2439c70 running Apr 18 19:58:43 dm900 enigma2[241]: no version filtering Apr 18 19:58:43 dm900 enigma2[241]: 0012: 4e 00 6b 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: mask: ff ff ff 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: ok ... now we start!! Apr 18 19:58:43 dm900 enigma2[241]: no version filtering Apr 18 19:58:43 dm900 enigma2[241]: 0000: 00 00 00 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: mask: ff 00 00 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:58:43 dm900 enigma2[241]: [EPGC] next update in 57 min Apr 18 19:58:43 dm900 enigma2[241]: [HbbTV].aitInvalidated Apr 18 19:58:43 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:58:43 dm900 enigma2[241]: I/ [InfoBar.__restoreState] :: Apr 18 19:58:43 dm900 enigma2[241]: create buffer for widget 1920 x 250 Apr 18 19:58:43 dm900 enigma2[241]: eBackgroundFileEraser '/media/network/Timeshift+Record/Timeshift_DM900UH D//timeshift.Fj1Lq1' finished Apr 18 19:58:43 dm900 enigma2[241]: [eFileMonitor] File {/tmp/ecm.info} has been deleted Apr 18 19:58:43 dm900 enigma2[241]: eTsRemoteSource::state transition NULL -> READY Apr 18 19:58:44 dm900 enigma2[241]: wait for driver eof ok Apr 18 19:58:44 dm900 enigma2[241]: reached EOF, but we are in stream mode. delaying 1 second. Apr 18 19:58:44 dm900 enigma2[241]: eDVBChannel: End of file! Apr 18 19:58:44 dm900 enigma2[241]: not pauseable. Apr 18 19:58:44 dm900 enigma2[241]: I/ [PlayerImpl.stopPolling] :: Apr 18 19:58:44 dm900 enigma2[241]: I/ [PlayerImpl.stopPolling] :: Apr 18 19:58:44 dm900 enigma2[241]: I/ [UPnPPlayer.stopPolling] :: Apr 18 19:58:44 dm900 enigma2[241]: I/ [UPnPPlayer.stopPolling] :: Apr 18 19:58:44 dm900 enigma2[241]: [eDBusInterface::Private::sendEvent 0 Apr 18 19:58:44 dm900 enigma2[241]: eTsRemoteSource::state transition READY -> PAUSED Apr 18 19:58:44 dm900 enigma2[241]: eTsRemoteSource::state transition PAUSED -> PLAYING Apr 18 19:58:45 dm900 enigma2[241]: + 1/1 TID 00 Apr 18 19:58:45 dm900 enigma2[241]: done! Apr 18 19:58:45 dm900 enigma2[241]: PATready Apr 18 19:58:45 dm900 enigma2[241]: use pmtpid 0062 for service_id 006b Apr 18 19:58:45 dm900 enigma2[241]: no version filtering Apr 18 19:58:45 dm900 enigma2[241]: 0062: 02 00 6b 00 00 00 Apr 18 19:58:45 dm900 enigma2[241]: mask: ff ff ff 00 00 00 Apr 18 19:58:45 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:58:45 dm900 enigma2[241]: doing version filtering Apr 18 19:58:45 dm900 enigma2[241]: 0000: 00 00 00 11 00 00 Apr 18 19:58:45 dm900 enigma2[241]: mask: ff 00 00 3f 00 00 Apr 18 19:58:45 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:58:45 dm900 enigma2[241]: +- 1/2 TID 4e Apr 18 19:58:45 dm900 enigma2[241]: ++ 2/2 TID 4e Apr 18 19:58:45 dm900 enigma2[241]: done! Apr 18 19:58:45 dm900 enigma2[241]: W/ [InfoBar.__servicePlaying] :: Apr 18 19:58:45 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: None None 1524074325.41 Apr 18 19:58:45 dm900 enigma2[241]: doing version filtering Apr 18 19:58:45 dm900 enigma2[241]: 0012: 4e 00 6b 0d 00 00 Apr 18 19:58:45 dm900 enigma2[241]: mask: ff ff ff 3f 00 00 Apr 18 19:58:45 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:58:45 dm900 enigma2[241]: VIDEO_GET_EVENT - ok Apr 18 19:58:45 dm900 enigma2[241]: unhandled DVBAPI Video Event 32 Apr 18 19:58:45 dm900 enigma2[241]: [eDVBLocalTimeHandler] Receiver time is 'Wed Apr 18 19:58:45 2018' Apr 18 19:58:31 dm900 enigma2[241]: [eDVBLocalTimeHandler] Transponder time is 'Wed Apr 18 19:58:31 2018' Apr 18 19:58:31 dm900 enigma2[241]: [eDVBLocalTimeHandler] diff is -14 Apr 18 19:58:31 dm900 enigma2[241]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Apr 18 19:58:31 dm900 enigma2[241]: [eDVBLocalTimeHandler] update RTC Apr 18 19:58:31 dm900 enigma2[241]: [eDVBLocalTimeHandler] time_difference is -14 Apr 18 19:58:31 dm900 enigma2[241]: [eDVBLocalTimeHandler] update Linux Time Apr 18 19:58:45 dm900 enigma2[241]: release cached channel (timer timeout) Apr 18 19:58:45 dm900 enigma2[241]: [eDVBCAService] remove channel 0x2502d78 Apr 18 19:58:45 dm900 enigma2[241]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Apr 18 19:58:45 dm900 enigma2[241]: [eDVBLocalTimeHandler] remove channel 0x2502d78 Apr 18 19:58:45 dm900 enigma2[241]: [EPGC] remove channel 0x2502d78 Apr 18 19:58:45 dm900 enigma2[241]: stop release channel timer Apr 18 19:58:45 dm900 enigma2[241]: [ 0] preClose Apr 18 19:58:48 dm900 enigma2[241]: [ 0] prepareClose running loop Apr 18 19:58:48 dm900 enigma2[241]: [SEC-0] lock 0 Apr 18 19:58:48 dm900 enigma2[241]: [SEC-0] locked 0 Apr 18 19:58:48 dm900 enigma2[241]: [SEC-0] setVoltage 18V Apr 18 19:58:48 dm900 enigma2[241]: [SEC-0] sleep 10ms Apr 18 19:58:49 dm900 enigma2[241]: [SEC-0] sendDiseqc: 71200000 Apr 18 19:58:49 dm900 enigma2[241]: [SEC-0] sleep 5ms Apr 18 19:58:49 dm900 enigma2[241]: [SEC-0] setVoltage 13V Apr 18 19:58:49 dm900 enigma2[241]: [SEC-0] sleep 10ms Apr 18 19:58:49 dm900 enigma2[241]: [SEC-0] delayed close frontend Apr 18 19:58:49 dm900 enigma2[241]: [SEC-0] unlock 0 Apr 18 19:58:49 dm900 enigma2[241]: [ 0] close frontend Apr 18 19:58:49 dm900 enigma2[241]: [SEC-0] setVoltage 0V Apr 18 19:58:50 dm900 enigma2[241]: Start timeshift! Apr 18 19:58:50 dm900 enigma2[241]: timeshift recording to /media/network/Timeshift+Record/Timeshift_DM900UH D//timeshift.wdZBAv Apr 18 19:58:50 dm900 enigma2[241]: before: 1 Apr 18 19:58:50 dm900 enigma2[241]: after: 1 Apr 18 19:58:50 dm900 enigma2[241]: setIoPrio realtime level 7 ok Apr 18 19:58:50 dm900 enigma2[241]: eRecordFileThread (direct io) START Apr 18 19:58:50 dm900 enigma2[241]: record write thread succesfully created! Apr 18 19:58:50 dm900 enigma2[241]: setIoPrio realtime level 7 ok Apr 18 19:58:50 dm900 enigma2[241]: I/ [InfoBar.ptsSetNextPlaybackFile] :: [PTS-Plugin] setNextPlaybackFile(/media/netw ork/Film-SKYNET/Timeshift+Record/Timeshift_DM900UHD//pts_livebuffer.2) Apr 18 19:58:50 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:58:56 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:01 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:02 dm900 enigma2[241]: no ancillary data in audio stream... abort radiotext pes parser Apr 18 19:59:05 dm900 enigma2[241]: timeout 0062! Apr 18 19:59:05 dm900 enigma2[241]: DVB service failed to tune - error 4 Apr 18 19:59:05 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: tuning... Kanal ist ung ltig! Apr 18 19:59:05 dm900 enigma2[241]: (Zeit lief beim Lesen der PMT ab) 1524074345.11 Apr 18 19:59:05 dm900 enigma2[241]: create buffer for widget 700 x 205 Apr 18 19:59:06 dm900 enigma2[241]: It's now Mi 18 Apr 2018 19:59:06 CEST Apr 18 19:59:06 dm900 enigma2[241]: [EPGRefreshTimer] next real activation is Do 19 Apr 2018 06:00:01 CEST Apr 18 19:59:06 dm900 enigma2[241]: [timer.py] next activation: 1524074446 (in 99020 ms) Apr 18 19:59:07 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:13 dm900 enigma2[241]: [DLNA] MediaRenderer detected: Denon AVR-X4400H (MediaRenderer, uuid:20c94f97-b1d6-1 8a2-0080-0005cdf1bab6) Apr 18 19:59:13 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:13 dm900 enigma2[241]: [DLNA] MediaRenderer detected: Denon AVR-X4400H (MediaRenderer, uuid:20c94f97-b1d6-1 8a2-0080-0005cdf1bab6) Apr 18 19:59:13 dm900 enigma2[241]: [DLNA] MediaRenderer detected: Denon AVR-X4400H (MediaRenderer, uuid:20c94f97-b1d6-1 8a2-0080-0005cdf1bab6) Apr 18 19:59:13 dm900 enigma2[241]: [DLNA] MediaRenderer detected: Denon AVR-X4400H (MediaRenderer, uuid:20c94f97-b1d6-1 8a2-0080-0005cdf1bab6) Apr 18 19:59:13 dm900 enigma2[241]: poll: unhandled POLLERR/HUP/NVAL for fd 338(24) Apr 18 19:59:18 dm900 enigma2[241]: [EPGC] cleanupOutdated Apr 18 19:59:19 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:26 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:26 dm900 enigma2[241]: wait for driver eof timeout Apr 18 19:59:29 dm900 enigma2[241]: [eCec] handle message 0 -> 3 : 8f Apr 18 19:59:29 dm900 enigma2[241]: [eCec] Give Device Power Status Apr 18 19:59:29 dm900 enigma2[241]: [eCec] send 3 -> 0 : 90 00 Apr 18 19:59:33 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:39 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:45 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:50 dm900 enigma2[241]: [InfoBar] quickSelectGlobal key: right Apr 18 19:59:50 dm900 enigma2[241]: [InfoBar] exec from Screens.InfoBar import InfoBar Apr 18 19:59:50 dm900 enigma2[241]: if InfoBar and InfoBar.instance: Apr 18 19:59:50 dm900 enigma2[241]: InfoBar.zapDown(InfoBar.instance) Apr 18 19:59:50 dm900 enigma2[241]: playing 1:0:19:74:B:85:C00000:0:0:0:http%3a//root%3adreambox@192.168.1.16%3a8001/1%3 a256%3a19%3a74%3aB%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Action HD (DM920UHD) Apr 18 19:59:50 dm900 enigma2[241]: stopping thread. Apr 18 19:59:50 dm900 enigma2[241]: waiting for aio thread shutdown! Apr 18 19:59:50 dm900 enigma2[241]: record aio thread has finished Apr 18 19:59:50 dm900 enigma2[241]: eRecordFileThread (direct io) STOP Apr 18 19:59:50 dm900 enigma2[241]: thread joined 0 Apr 18 19:59:50 dm900 enigma2[241]: remove timeshift file Apr 18 19:59:50 dm900 enigma2[241]: stopping thread. Apr 18 19:59:50 dm900 enigma2[241]: wait for driver eof timeout Apr 18 19:59:50 dm900 enigma2[241]: FILEPUSH THREAD STOP Apr 18 19:59:50 dm900 enigma2[241]: thread joined 0 Apr 18 19:59:50 dm900 enigma2[241]: [eDVBCAService] remove channel 0x2439c70 Apr 18 19:59:50 dm900 enigma2[241]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Apr 18 19:59:50 dm900 enigma2[241]: [eDVBLocalTimeHandler] remove channel 0x2439c70 Apr 18 19:59:50 dm900 enigma2[241]: [EPGC] remove channel 0x2439c70 Apr 18 19:59:50 dm900 enigma2[241]: [TeleText] service stopped Apr 18 19:59:50 dm900 enigma2[241]: [TeleText] send array('B', [1, 0, 0, 0]) Apr 18 19:59:50 dm900 enigma2[241]: disable dvb subtitles Apr 18 19:59:50 dm900 enigma2[241]: disable teletext subtitles page ffffffffffffffff (und) Apr 18 19:59:50 dm900 enigma2[241]: decoder state: play, vpid=-1, apid=-1 Apr 18 19:59:50 dm900 enigma2[241]: DEMUX_STOP - video - ok Apr 18 19:59:50 dm900 enigma2[241]: VIDEO_STOP - ok Apr 18 19:59:50 dm900 enigma2[241]: AUDIO_STOP - ok Apr 18 19:59:50 dm900 enigma2[241]: DEMUX_STOP - audio - ok Apr 18 19:59:50 dm900 enigma2[241]: AUDIO_CONTINUE - ok Apr 18 19:59:50 dm900 enigma2[241]: eTsRemoteSource::close() Apr 18 19:59:50 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: connecting... Please wait! 1524074390.98 Apr 18 19:59:50 dm900 enigma2[241]: [TeleText] service started Apr 18 19:59:51 dm900 enigma2[241]: not pauseable. Apr 18 19:59:51 dm900 enigma2[241]: alloc PVR Apr 18 19:59:51 dm900 enigma2[241]: allocate channel.. 000b:0085:00c00000 (http://root:dreambox@192.168.1.16:8001/1:256: 19:74:B:85:C00000:0:0:0:) Apr 18 19:59:51 dm900 enigma2[241]: [eDVBCAService] new channel 0x2439c70! Apr 18 19:59:51 dm900 enigma2[241]: eTsRemoteSource::close() Apr 18 19:59:51 dm900 enigma2[241]: [eTsRemoteSource] souphttpsrc user-agent='GStreamer souphttpsrc libsoup/2.52.2;' Apr 18 19:59:51 dm900 enigma2[241]: allocate pvr demux Apr 18 19:59:51 dm900 enigma2[241]: have 1 video stream(s) (03ff), and 1 audio stream(s) (0403), and the pcr pid is 03ff , and the text pid is ffffffff Apr 18 19:59:51 dm900 enigma2[241]: allocate pvr demux Apr 18 19:59:51 dm900 enigma2[241]: disable teletext subtitles page ffffffffffffffff (und) Apr 18 19:59:51 dm900 enigma2[241]: PES filter: DMX_SET_PES_FILTER - Invalid argument Apr 18 19:59:51 dm900 enigma2[241]: decoder state: play, vpid=1023, apid=1027 Apr 18 19:59:51 dm900 enigma2[241]: DMX_SET_PES_FILTER(0x403) - audio - ok Apr 18 19:59:51 dm900 enigma2[241]: DEMUX_START - audio - ok Apr 18 19:59:51 dm900 enigma2[241]: AUDIO_SET_BYPASS(0) - ok Apr 18 19:59:51 dm900 enigma2[241]: AUDIO_PAUSE - ok Apr 18 19:59:51 dm900 enigma2[241]: AUDIO_PLAY - ok Apr 18 19:59:51 dm900 enigma2[241]: Video Device: /dev/dvb/adapter0/video0 Apr 18 19:59:51 dm900 enigma2[241]: demux device: /dev/dvb/adapter0/demux7 Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_SET_STREAMTYPE 1 - ok Apr 18 19:59:51 dm900 enigma2[241]: DMX_SET_PES_FILTER(0x3ff) - video - ok Apr 18 19:59:51 dm900 enigma2[241]: DEMUX_START - video - ok Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_FREEZE - ok Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_PLAY - ok Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_SLOWMOTION(0) - ok Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_FAST_FORWARD(0) - ok Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_CONTINUE - ok Apr 18 19:59:51 dm900 enigma2[241]: AUDIO_CONTINUE - ok Apr 18 19:59:51 dm900 enigma2[241]: W/ [InfoBar.__servicePlaying] :: Apr 18 19:59:51 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: None None 1524074391.02 Apr 18 19:59:51 dm900 enigma2[241]: not pauseable. Apr 18 19:59:51 dm900 enigma2[241]: loading streaminfo for http://root:dreambox@192.168.1.16:8001/1:256:19:74:B:85:C0000 0:0:0:0: Apr 18 19:59:51 dm900 enigma2[241]: cutfile not found! Apr 18 19:59:51 dm900 enigma2[241]: no cue sheet Apr 18 19:59:51 dm900 enigma2[241]: before: 1 Apr 18 19:59:51 dm900 enigma2[241]: after: 1 Apr 18 19:59:51 dm900 enigma2[241]: setIoPrio best-effort level 5 ok Apr 18 19:59:51 dm900 enigma2[241]: FILEPUSH THREAD START Apr 18 19:59:51 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:51 dm900 enigma2[241]: [eDVBCAService] channel 0x2439c70 running Apr 18 19:59:51 dm900 enigma2[241]: [eDVBLocalTimeHandler] channel 0x2439c70 running Apr 18 19:59:51 dm900 enigma2[241]: no version filtering Apr 18 19:59:51 dm900 enigma2[241]: 0014: 70 00 00 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mask: fc 00 00 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: [EPGC] channel 0x2439c70 running Apr 18 19:59:51 dm900 enigma2[241]: [EPGC] next update in 2 sec Apr 18 19:59:51 dm900 enigma2[241]: no version filtering Apr 18 19:59:51 dm900 enigma2[241]: 0012: 4e 00 74 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mask: ff ff ff 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: ok ... now we start!! Apr 18 19:59:51 dm900 enigma2[241]: no version filtering Apr 18 19:59:51 dm900 enigma2[241]: 0000: 00 00 00 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mask: ff 00 00 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: [HbbTV].aitInvalidated Apr 18 19:59:51 dm900 enigma2[241]: eBackgroundFileEraser '/media/network/Timeshift+Record/Timeshift_DM900UH D//timeshift.wdZBAv' finished Apr 18 19:59:51 dm900 enigma2[241]: eTsRemoteSource::state transition NULL -> READY Apr 18 19:59:51 dm900 enigma2[241]: eTsRemoteSource::state transition READY -> PAUSED Apr 18 19:59:51 dm900 enigma2[241]: eTsRemoteSource::state transition PAUSED -> PLAYING Apr 18 19:59:51 dm900 enigma2[241]: + 1/1 TID 00 Apr 18 19:59:51 dm900 enigma2[241]: done! Apr 18 19:59:51 dm900 enigma2[241]: PATready Apr 18 19:59:51 dm900 enigma2[241]: use pmtpid 0063 for service_id 0074 Apr 18 19:59:51 dm900 enigma2[241]: no version filtering Apr 18 19:59:51 dm900 enigma2[241]: 0063: 02 00 74 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mask: ff ff ff 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:59:51 dm900 enigma2[241]: doing version filtering Apr 18 19:59:51 dm900 enigma2[241]: 0000: 00 00 00 23 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mask: ff 00 00 3f 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:59:37 dm900 enigma2[241]: [eDVBLocalTimeHandler] Receiver time is 'Wed Apr 18 19:59:51 2018' Apr 18 19:59:37 dm900 enigma2[241]: [eDVBLocalTimeHandler] Transponder time is 'Wed Apr 18 19:59:37 2018' Apr 18 19:59:37 dm900 enigma2[241]: [eDVBLocalTimeHandler] diff is -14 Apr 18 19:59:37 dm900 enigma2[241]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Apr 18 19:59:37 dm900 enigma2[241]: [eDVBLocalTimeHandler] update RTC Apr 18 19:59:37 dm900 enigma2[241]: [eDVBLocalTimeHandler] time_difference is -14 Apr 18 19:59:37 dm900 enigma2[241]: [eDVBLocalTimeHandler] update Linux Time Apr 18 19:59:51 dm900 enigma2[241]: + 1/1 TID 02 Apr 18 19:59:51 dm900 enigma2[241]: done! Apr 18 19:59:51 dm900 enigma2[241]: [eDVBCAService] new service 1:0:19:74:B:85:C00000:0:0:0:http%3a//root%3adreambox@192 .168.1.16%3a8001/1%3a256%3a19%3a74%3aB%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Action HD (DM920UHD) Apr 18 19:59:51 dm900 enigma2[241]: [eDVBCAService] add demux 7 to slot 0 service 1:0:19:74:B:85:C00000:0:0:0:http%3a//r oot%3adreambox@192.168.1.16%3a8001/1%3a256%3a19%3a74%3aB%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Action HD (DM920UHD) Apr 18 19:59:51 dm900 enigma2[241]: [eDVBCAService] demux 7 mask 80 Apr 18 19:59:51 dm900 enigma2[241]: [eDVBCAServiceConn] send 73 bytes to '/tmp/camd.socket' Apr 18 19:59:51 dm900 enigma2[241]: 9f 80 32 45 03 00 74 3f 00 30 01 81 08 00 c0 00 00 00 0b 00 85 82 02 80 07 84 02 00 63 85 00 86 01 07 09 04 09 8c fa 11 09 04 09 c4 fb 11 09 04 09 af ff 11 09 04 09 8d fc 11 1b 03 ff 00 00 06 04 03 00 00 06 04 04 00 00 Apr 18 19:59:51 dm900 enigma2[241]: eventNewProgramInfo 0 0 Apr 18 19:59:51 dm900 enigma2[241]: have 1 video stream(s) (03ff), and 2 audio stream(s) (0403, 0404), and the pcr pid i s 03ff, and the text pid is ffffffff Apr 18 19:59:51 dm900 enigma2[241]: PES filter: DMX_SET_PES_FILTER - Invalid argument Apr 18 19:59:51 dm900 enigma2[241]: decoder state: play, vpid=1023, apid=1027 Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_SLOWMOTION(0) - ok Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_FAST_FORWARD(0) - ok Apr 18 19:59:51 dm900 enigma2[241]: VIDEO_CONTINUE - ok Apr 18 19:59:51 dm900 enigma2[241]: AUDIO_CONTINUE - ok Apr 18 19:59:51 dm900 enigma2[241]: W/ [InfoBar.__servicePlaying] :: Apr 18 19:59:51 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: None None 1524074391.59 Apr 18 19:59:51 dm900 enigma2[241]: [eDBusInterface::Private::sendEvent 1 Apr 18 19:59:51 dm900 enigma2[241]: [TeleText] serviceInfoChanged Apr 18 19:59:51 dm900 enigma2[241]: [TeleText] TXT PID -1 DEMUX 7 Apr 18 19:59:51 dm900 enigma2[241]: doing version filtering Apr 18 19:59:51 dm900 enigma2[241]: 0063: 02 00 74 3f 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mask: ff ff ff 3f 00 00 Apr 18 19:59:51 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:59:52 dm900 enigma2[241]: VIDEO_GET_EVENT - ok Apr 18 19:59:52 dm900 enigma2[241]: unhandled DVBAPI Video Event 32 Apr 18 19:59:52 dm900 enigma2[241]: +- 1/2 TID 4e Apr 18 19:59:52 dm900 enigma2[241]: ++ 2/2 TID 4e Apr 18 19:59:52 dm900 enigma2[241]: done! Apr 18 19:59:52 dm900 enigma2[241]: W/ [InfoBar.__servicePlaying] :: Apr 18 19:59:52 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: None None 1524074392.96 Apr 18 19:59:52 dm900 enigma2[241]: [TrackAutoselector]:doSelect self._stype servicedvb in handle_services ['servicegst' , 'servicedvb'] True event audio Apr 18 19:59:52 dm900 enigma2[241]: [TrackAutoselector]:selectAudio list of audio streams [, ] Apr 18 19:59:52 dm900 enigma2[241]: [TrackAutoselector]:selectAudio now sorted by language [, ] Apr 18 19:59:52 dm900 enigma2[241]: [TrackAutoselector]:selectAudio now sorted by codec format [, ] Apr 18 19:59:52 dm900 enigma2[241]: [TrackAutoselector]:selectAudio now sorted by saved flag [, ] Apr 18 19:59:52 dm900 enigma2[241]: [TrackAutoselector]:selectAudio final resorted list of audio streams: [, ] selectedAudio: 0 Apr 18 19:59:52 dm900 enigma2[241]: [TrackAutoselector]:doSelect self._stype servicedvb in handle_services ['servicegst' , 'servicedvb'] True event subtitles Apr 18 19:59:52 dm900 enigma2[241]: doing version filtering Apr 18 19:59:52 dm900 enigma2[241]: 0012: 4e 00 74 35 00 00 Apr 18 19:59:52 dm900 enigma2[241]: mask: ff ff ff 3f 00 00 Apr 18 19:59:52 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:59:53 dm900 enigma2[241]: [EPGC] start caching events(1524074393) Apr 18 19:59:53 dm900 enigma2[241]: [InfoBar] quickSelectGlobal key: left Apr 18 19:59:53 dm900 enigma2[241]: [InfoBar] exec from Screens.InfoBar import InfoBar Apr 18 19:59:53 dm900 enigma2[241]: if InfoBar and InfoBar.instance: Apr 18 19:59:53 dm900 enigma2[241]: InfoBar.zapUp(InfoBar.instance) Apr 18 19:59:53 dm900 enigma2[241]: playing 1:0:19:6B:C:85:C00000:0:0:0:http%3a//root%3adreambox@192.168.1.16%3a8001/1%3 a256%3a19%3a6B%3aC%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Alien HD (DM920UHD) Apr 18 19:59:53 dm900 enigma2[241]: [eDVBCAService] free slot 0 demux 7 for service 1:0:19:74:B:85:C00000:0:0:0:http%3a/ /root%3adreambox@192.168.1.16%3a8001/1%3a256%3a19%3a74%3aB%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Action HD (DM920UHD) Apr 18 19:59:53 dm900 enigma2[241]: [eDVBCAService] free service 1:0:19:74:B:85:C00000:0:0:0:http%3a//root%3adreambox@19 2.168.1.16%3a8001/1%3a256%3a19%3a74%3aB%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Action HD (DM920UHD) Apr 18 19:59:53 dm900 enigma2[241]: stopping thread. Apr 18 19:59:53 dm900 enigma2[241]: wait for driver eof timeout Apr 18 19:59:53 dm900 enigma2[241]: FILEPUSH THREAD STOP Apr 18 19:59:53 dm900 enigma2[241]: thread joined 0 Apr 18 19:59:53 dm900 enigma2[241]: [eDVBCAService] remove channel 0x2439c70 Apr 18 19:59:53 dm900 enigma2[241]: [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok Apr 18 19:59:53 dm900 enigma2[241]: [eDVBLocalTimeHandler] remove channel 0x2439c70 Apr 18 19:59:53 dm900 enigma2[241]: [EPGC] remove channel 0x2439c70 Apr 18 19:59:53 dm900 enigma2[241]: [EPGC] abort caching events !! Apr 18 19:59:53 dm900 enigma2[241]: [eDBusInterface::Private::sendEvent 0 Apr 18 19:59:53 dm900 enigma2[241]: [TeleText] service stopped Apr 18 19:59:53 dm900 enigma2[241]: [TeleText] send array('B', [1, 0, 0, 0]) Apr 18 19:59:53 dm900 enigma2[241]: disable dvb subtitles Apr 18 19:59:53 dm900 enigma2[241]: disable teletext subtitles page ffffffffffffffff (und) Apr 18 19:59:53 dm900 enigma2[241]: decoder state: play, vpid=-1, apid=-1 Apr 18 19:59:53 dm900 enigma2[241]: DEMUX_STOP - video - ok Apr 18 19:59:53 dm900 enigma2[241]: VIDEO_STOP - ok Apr 18 19:59:53 dm900 enigma2[241]: AUDIO_STOP - ok Apr 18 19:59:53 dm900 enigma2[241]: DEMUX_STOP - audio - ok Apr 18 19:59:53 dm900 enigma2[241]: AUDIO_CONTINUE - ok Apr 18 19:59:53 dm900 enigma2[241]: eTsRemoteSource::close() Apr 18 19:59:53 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: connecting... Please wait! 1524074393.76 Apr 18 19:59:53 dm900 enigma2[241]: create buffer for widget 700 x 205 Apr 18 19:59:53 dm900 enigma2[241]: [TeleText] service started Apr 18 19:59:53 dm900 enigma2[241]: not pauseable. Apr 18 19:59:53 dm900 enigma2[241]: alloc PVR Apr 18 19:59:53 dm900 enigma2[241]: allocate channel.. 000c:0085:00c00000 (http://root:dreambox@192.168.1.16:8001/1:256: 19:6B:C:85:C00000:0:0:0:) Apr 18 19:59:53 dm900 enigma2[241]: [eDVBCAService] new channel 0x2439c70! Apr 18 19:59:53 dm900 enigma2[241]: eTsRemoteSource::close() Apr 18 19:59:53 dm900 enigma2[241]: [eTsRemoteSource] souphttpsrc user-agent='GStreamer souphttpsrc libsoup/2.52.2;' Apr 18 19:59:53 dm900 enigma2[241]: allocate pvr demux Apr 18 19:59:53 dm900 enigma2[241]: have 1 video stream(s) (02ff), and 1 audio stream(s) (0303), and the pcr pid is 02ff , and the text pid is ffffffff Apr 18 19:59:53 dm900 enigma2[241]: allocate pvr demux Apr 18 19:59:53 dm900 enigma2[241]: disable teletext subtitles page ffffffffffffffff (und) Apr 18 19:59:53 dm900 enigma2[241]: PES filter: DMX_SET_PES_FILTER - Invalid argument Apr 18 19:59:53 dm900 enigma2[241]: decoder state: play, vpid=767, apid=771 Apr 18 19:59:53 dm900 enigma2[241]: DMX_SET_PES_FILTER(0x303) - audio - ok Apr 18 19:59:53 dm900 enigma2[241]: DEMUX_START - audio - ok Apr 18 19:59:53 dm900 enigma2[241]: AUDIO_SET_BYPASS(0) - ok Apr 18 19:59:53 dm900 enigma2[241]: AUDIO_PAUSE - ok Apr 18 19:59:53 dm900 enigma2[241]: AUDIO_PLAY - ok Apr 18 19:59:53 dm900 enigma2[241]: Video Device: /dev/dvb/adapter0/video0 Apr 18 19:59:53 dm900 enigma2[241]: demux device: /dev/dvb/adapter0/demux7 Apr 18 19:59:53 dm900 enigma2[241]: VIDEO_SET_STREAMTYPE 1 - ok Apr 18 19:59:53 dm900 enigma2[241]: DMX_SET_PES_FILTER(0x2ff) - video - ok Apr 18 19:59:53 dm900 enigma2[241]: DEMUX_START - video - ok Apr 18 19:59:53 dm900 enigma2[241]: VIDEO_FREEZE - ok Apr 18 19:59:53 dm900 enigma2[241]: VIDEO_PLAY - ok Apr 18 19:59:53 dm900 enigma2[241]: VIDEO_SLOWMOTION(0) - ok Apr 18 19:59:53 dm900 enigma2[241]: VIDEO_FAST_FORWARD(0) - ok Apr 18 19:59:53 dm900 enigma2[241]: VIDEO_CONTINUE - ok Apr 18 19:59:53 dm900 enigma2[241]: AUDIO_CONTINUE - ok Apr 18 19:59:53 dm900 enigma2[241]: W/ [InfoBar.__servicePlaying] :: Apr 18 19:59:53 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: None None 1524074393.81 Apr 18 19:59:53 dm900 enigma2[241]: not pauseable. Apr 18 19:59:53 dm900 enigma2[241]: loading streaminfo for http://root:dreambox@192.168.1.16:8001/1:256:19:6B:C:85:C0000 0:0:0:0: Apr 18 19:59:53 dm900 enigma2[241]: cutfile not found! Apr 18 19:59:53 dm900 enigma2[241]: no cue sheet Apr 18 19:59:53 dm900 enigma2[241]: before: 1 Apr 18 19:59:53 dm900 enigma2[241]: after: 1 Apr 18 19:59:53 dm900 enigma2[241]: setIoPrio best-effort level 5 ok Apr 18 19:59:53 dm900 enigma2[241]: FILEPUSH THREAD START Apr 18 19:59:53 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 19:59:53 dm900 enigma2[241]: [eDVBCAService] channel 0x2439c70 running Apr 18 19:59:53 dm900 enigma2[241]: [eDVBLocalTimeHandler] channel 0x2439c70 running Apr 18 19:59:53 dm900 enigma2[241]: no version filtering Apr 18 19:59:53 dm900 enigma2[241]: 0014: 70 00 00 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: mask: fc 00 00 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: [EPGC] channel 0x2439c70 running Apr 18 19:59:53 dm900 enigma2[241]: no version filtering Apr 18 19:59:53 dm900 enigma2[241]: 0012: 4e 00 6b 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: mask: ff ff ff 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: [EPGC] next update in 56 min Apr 18 19:59:53 dm900 enigma2[241]: ok ... now we start!! Apr 18 19:59:53 dm900 enigma2[241]: no version filtering Apr 18 19:59:53 dm900 enigma2[241]: 0000: 00 00 00 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: mask: ff 00 00 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:59:53 dm900 enigma2[241]: [HbbTV].aitInvalidated Apr 18 19:59:53 dm900 enigma2[241]: eTsRemoteSource::state transition NULL -> READY Apr 18 19:59:53 dm900 enigma2[241]: eTsRemoteSource::state transition READY -> PAUSED Apr 18 19:59:53 dm900 enigma2[241]: eTsRemoteSource::state transition PAUSED -> PLAYING Apr 18 19:59:53 dm900 enigma2[241]: + 1/1 TID 00 Apr 18 19:59:53 dm900 enigma2[241]: done! Apr 18 19:59:53 dm900 enigma2[241]: PATready Apr 18 19:59:53 dm900 enigma2[241]: no PAT entry found.. start delay Apr 18 19:59:53 dm900 enigma2[241]: doing version filtering Apr 18 19:59:53 dm900 enigma2[241]: 0000: 00 00 00 23 00 00 Apr 18 19:59:53 dm900 enigma2[241]: mask: ff 00 00 3f 00 00 Apr 18 19:59:53 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:59:53 dm900 enigma2[241]: [EPGC] cleanup invalid data Apr 18 19:59:54 dm900 enigma2[241]: + 1/1 TID 00 Apr 18 19:59:54 dm900 enigma2[241]: done! Apr 18 19:59:54 dm900 enigma2[241]: PATready Apr 18 19:59:54 dm900 enigma2[241]: use pmtpid 0062 for service_id 006b Apr 18 19:59:54 dm900 enigma2[241]: no version filtering Apr 18 19:59:54 dm900 enigma2[241]: 0062: 02 00 6b 00 00 00 Apr 18 19:59:54 dm900 enigma2[241]: mask: ff ff ff 00 00 00 Apr 18 19:59:54 dm900 enigma2[241]: mode: 00 00 00 00 00 00 Apr 18 19:59:54 dm900 enigma2[241]: doing version filtering Apr 18 19:59:54 dm900 enigma2[241]: 0000: 00 00 00 11 00 00 Apr 18 19:59:54 dm900 enigma2[241]: mask: ff 00 00 3f 00 00 Apr 18 19:59:54 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:59:54 dm900 enigma2[241]: +- 1/2 TID 4e Apr 18 19:59:54 dm900 enigma2[241]: + 1/1 TID 02 Apr 18 19:59:54 dm900 enigma2[241]: done! Apr 18 19:59:54 dm900 enigma2[241]: [eDVBCAService] new service 1:0:19:6B:C:85:C00000:0:0:0:http%3a//root%3adreambox@192 .168.1.16%3a8001/1%3a256%3a19%3a6B%3aC%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Alien HD (DM920UHD) Apr 18 19:59:54 dm900 enigma2[241]: [eDVBCAService] add demux 7 to slot 0 service 1:0:19:6B:C:85:C00000:0:0:0:http%3a//r oot%3adreambox@192.168.1.16%3a8001/1%3a256%3a19%3a6B%3aC%3a85%3aC00000%3a0%3a0%3a0%3a:Sky Cinema Alien HD (DM920UHD) Apr 18 19:59:54 dm900 enigma2[241]: [eDVBCAService] demux 7 mask 80 Apr 18 19:59:54 dm900 enigma2[241]: [eDVBCAServiceConn] send 73 bytes to '/tmp/camd.socket' Apr 18 19:59:54 dm900 enigma2[241]: 9f 80 32 45 03 00 6b 1b 00 30 01 81 08 00 c0 00 00 00 0c 00 85 82 02 80 07 84 02 00 62 85 00 86 01 07 09 04 09 c4 fb 0b 09 04 09 8c fa 0b 09 04 09 af ff 0b 09 04 09 8d fc 0b 1b 02 ff 00 00 06 03 03 00 00 06 03 04 00 00 Apr 18 19:59:54 dm900 enigma2[241]: eventNewProgramInfo 0 0 Apr 18 19:59:54 dm900 enigma2[241]: have 1 video stream(s) (02ff), and 2 audio stream(s) (0303, 0304), and the pcr pid i s 02ff, and the text pid is ffffffff Apr 18 19:59:54 dm900 enigma2[241]: PES filter: DMX_SET_PES_FILTER - Invalid argument Apr 18 19:59:54 dm900 enigma2[241]: decoder state: play, vpid=767, apid=771 Apr 18 19:59:54 dm900 enigma2[241]: VIDEO_SLOWMOTION(0) - ok Apr 18 19:59:54 dm900 enigma2[241]: VIDEO_FAST_FORWARD(0) - ok Apr 18 19:59:54 dm900 enigma2[241]: VIDEO_CONTINUE - ok Apr 18 19:59:54 dm900 enigma2[241]: AUDIO_CONTINUE - ok Apr 18 19:59:54 dm900 enigma2[241]: W/ [InfoBar.__servicePlaying] :: Apr 18 19:59:54 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: None None 1524074394.35 Apr 18 19:59:54 dm900 enigma2[241]: [eDBusInterface::Private::sendEvent 1 Apr 18 19:59:54 dm900 enigma2[241]: [TeleText] serviceInfoChanged Apr 18 19:59:54 dm900 enigma2[241]: [TeleText] TXT PID -1 DEMUX 7 Apr 18 19:59:54 dm900 enigma2[241]: doing version filtering Apr 18 19:59:54 dm900 enigma2[241]: 0062: 02 00 6b 1b 00 00 Apr 18 19:59:54 dm900 enigma2[241]: mask: ff ff ff 3f 00 00 Apr 18 19:59:54 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:59:54 dm900 enigma2[241]: [eDVBLocalTimeHandler] Receiver time is 'Wed Apr 18 19:59:54 2018' Apr 18 19:59:54 dm900 enigma2[241]: [eDVBLocalTimeHandler] Transponder time is 'Wed Apr 18 19:59:40 2018' Apr 18 19:59:54 dm900 enigma2[241]: [eDVBLocalTimeHandler] diff is -14 Apr 18 19:59:54 dm900 enigma2[241]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time Apr 18 19:59:54 dm900 enigma2[241]: [eDVBLocalTimeHandler] update RTC Apr 18 19:59:54 dm900 enigma2[241]: [eDVBLocalTimeHandler] time_difference is -14 Apr 18 19:59:54 dm900 enigma2[241]: [eDVBLocalTimeHandler] update Linux Time Apr 18 19:59:55 dm900 enigma2[241]: ++ 2/2 TID 4e Apr 18 19:59:55 dm900 enigma2[241]: done! Apr 18 19:59:55 dm900 enigma2[241]: W/ [InfoBar.__servicePlaying] :: Apr 18 19:59:55 dm900 enigma2[241]: I/ [InfoBar.setPlaybackState] :: None None 1524074395.25 Apr 18 19:59:55 dm900 enigma2[241]: [TrackAutoselector]:doSelect self._stype servicedvb in handle_services ['servicegst' , 'servicedvb'] True event audio Apr 18 19:59:55 dm900 enigma2[241]: [TrackAutoselector]:selectAudio list of audio streams [, ] Apr 18 19:59:55 dm900 enigma2[241]: [TrackAutoselector]:selectAudio now sorted by language [, ] Apr 18 19:59:55 dm900 enigma2[241]: [TrackAutoselector]:selectAudio now sorted by codec format [, ] Apr 18 19:59:55 dm900 enigma2[241]: [TrackAutoselector]:selectAudio now sorted by saved flag [, ] Apr 18 19:59:55 dm900 enigma2[241]: [TrackAutoselector]:selectAudio final resorted list of audio streams: [, ] selectedAudio: 0 Apr 18 19:59:55 dm900 enigma2[241]: [TrackAutoselector]:doSelect self._stype servicedvb in handle_services ['servicegst' , 'servicedvb'] True event subtitles Apr 18 19:59:55 dm900 enigma2[241]: doing version filtering Apr 18 19:59:55 dm900 enigma2[241]: 0012: 4e 00 6b 0d 00 00 Apr 18 19:59:55 dm900 enigma2[241]: mask: ff ff ff 3f 00 00 Apr 18 19:59:55 dm900 enigma2[241]: mode: 00 00 00 3e 00 00 Apr 18 19:59:55 dm900 enigma2[241]: VIDEO_GET_EVENT - ok Apr 18 19:59:55 dm900 enigma2[241]: unhandled DVBAPI Video Event 32 Apr 18 20:00:00 dm900 enigma2[241]: Start timeshift! Apr 18 20:00:00 dm900 enigma2[241]: timeshift recording to /media/network/Timeshift+Record/Timeshift_DM900UH D//timeshift.JBKJRM Apr 18 20:00:00 dm900 enigma2[241]: before: 1 Apr 18 20:00:00 dm900 enigma2[241]: after: 1 Apr 18 20:00:00 dm900 enigma2[241]: setIoPrio realtime level 7 ok Apr 18 20:00:00 dm900 enigma2[241]: I/ [InfoBar.ptsSetNextPlaybackFile] :: [PTS-Plugin] setNextPlaybackFile(/media/netw ork/Film-SKYNET/Timeshift+Record/Timeshift_DM900UHD//pts_livebuffer.1) Apr 18 20:00:00 dm900 enigma2[241]: eRecordFileThread (direct io) START Apr 18 20:00:00 dm900 enigma2[241]: record write thread succesfully created! Apr 18 20:00:00 dm900 enigma2[241]: setIoPrio realtime level 7 ok Apr 18 20:00:01 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 20:00:07 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 20:00:13 dm900 enigma2[241]: no ancillary data in audio stream... abort radiotext pes parser Apr 18 20:00:13 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 20:00:14 dm900 enigma2[241]: It's now Mi 18 Apr 2018 20:00:14 CEST Apr 18 20:00:14 dm900 enigma2[241]: [timer.py] next activation: 1524074514 (in 99025 ms) Apr 18 20:00:15 dm900 enigma2[241]: It's now Mi 18 Apr 2018 20:00:15 CEST Apr 18 20:00:15 dm900 enigma2[241]: [timer.py] next activation: 1524074515 (in 99026 ms) Apr 18 20:00:18 dm900 enigma2[241]: [EPGC] cleanupOutdated Apr 18 20:00:20 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 20:00:27 dm900 enigma2[241]: no cue sheet. forcing normal play Apr 18 20:00:29 dm900 enigma2[241]: [eCec] handle message 0 -> 3 : 8f Apr 18 20:00:29 dm900 enigma2[241]: [eCec] Give Device Power Status Apr 18 20:00:29 dm900 enigma2[241]: [eCec] send 3 -> 0 : 90 00 Apr 18 20:00:36 dm900 enigma2[241]: no cue sheet. forcing normal play ^C