Hallo,

mir ist heute zum 2ten mal aufgefallen das eine Meldung auf dem Bildschirm zu sehen ist, das eine Timeraufnahme nicht gestartet werden konnte. Die Aufnahme wurde aber getätigt. Es wird auf ein NAS aufgenommen, welches kurz anlaufen muß, könnte daran liegen. Ich habe einen kleinen Auszug aus dem Log. Ist es nicht auch so, das bei einer beginnenden Aufnahme auf den endsprechenden Sender geschaltet wird? Das war z.B. nicht der Fall. Oder es wurde wieder auf den vorherigen Sender zurück gestellt. Das weiß ich nun gerade nicht.

Code:
16:04:54.042 [   ] dvb/cahandler.cpp:578 buildCAPMT [eDVBCAService] don't build/send the same CA PMT twice
16:04:55.386 [   ] service/servicedvbrecord.cpp:527 gotNewEvent [eDVBServiceRecord] now running: Gute Zeiten, schlechte Zeiten (1500 seconds)
16:05:00.486 {   } RecordTimer.py:193 log [TIMER] start recording
16:05:00.490 {   } Components/FanControl.py:25 setVoltage_PWM [FanControl]: setting fan values: fanid = 0, voltage = 15, pwm = 50
16:05:00.492 [   ] service/servicedvbrecord.cpp:276 doRecord Recording to /media/net/NASSERVER/movie/20151023 1605 - RTL Passion - Gute Zeiten, schlechte Zeiten.ts...
16:05:00.613 <   > service/servicedvbrecord.cpp:316 doRecord start recording...RECORD: have 1 video stream(s) (00ff), and 2 audio stream(s) (0100, 0101), and the pcr pid is 00ff, and the text pid is 0020
16:05:00.632 [   ] service/servicedvbrecord.cpp:432 doRecord ADD PID: 0000
16:05:00.634 [   ] service/servicedvbrecord.cpp:432 doRecord ADD PID: 0020
16:05:00.635 [   ] service/servicedvbrecord.cpp:432 doRecord ADD PID: 0063
16:05:00.637 [   ] service/servicedvbrecord.cpp:432 doRecord ADD PID: 00ff
16:05:00.638 [   ] service/servicedvbrecord.cpp:432 doRecord ADD PID: 0100
16:05:00.639 [   ] service/servicedvbrecord.cpp:432 doRecord ADD PID: 0101
16:05:00.671 [   ] base/ioprio.cpp:83 setIoPrio setIoPrio realtime level 7 ok
16:05:00.673 [   ] base/filepush.cpp:372 thread [eFilePushThreadRecorder] THREAD START
16:05:00.679 {   } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: [emcRC] REC START for: 20151023 1605 - RTL Passion - Gute Zeiten, schlechte Zeiten
16:05:00.682 {   } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: [MC] Timer started - add recording
16:05:03.686 {   } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: [EMC] LOAD PATH:
/media/net/NASSERVER/movie/20151023 1605 - RTL Passion - Gute Zeiten, schlechte Zeiten.ts16:05:03.688 {   } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut 
16:05:32.672 {   } Plugins/Extensions/AutoTimer/AutoPoller.py:35 query [AutoTimer] Auto Poll
16:05:32.675 {   } Plugins/Extensions/AutoTimer/AutoPoller.py:49 query [AutoTimer] Auto Poll Started
16:05:32.677 {   } Plugins/Extensions/AutoTimer/AutoTimer.py:100 readXml [AutoTimer] No changes in configuration, won't parse
16:05:32.679 [ E ] dvb/epgcache.cpp:2266 lookupEventId [EPGC] event d6a8 not found in epgcache
16:05:32.681 [ E ] dvb/epgcache.cpp:2266 lookupEventId [EPGC] event d791 not found in epgcache
16:05:32.682 [ E ] dvb/epgcache.cpp:2266 lookupEventId [EPGC] event d799 not found in epgcache
16:05:32.684 [ E ] dvb/epgcache.cpp:2266 lookupEventId [EPGC] event d79e not found in epgcache
16:05:32.686 [ E ] dvb/epgcache.cpp:2266 lookupEventId [EPGC] event d7bb not found in epgcache
16:05:32.687 [ E ] dvb/epgcache.cpp:2266 lookupEventId [EPGC] event d7c3 not found in epgcache
16:05:32.692 {   } Components/Task.py:375 jobDone job Components.Task.Job name=AutoTimer #tasks=0 completed with [] in None
16:12:29.056 {   } Components/Renderer/MetrixHDWeatherUpdaterStandalone.py:91 getWeatherThread MetrixHDWeatherStandalone lookup for ID 15002838
16:12:29.679 {   } Components/Renderer/MetrixHDWeatherUpdaterStandalone.py:91 getWeatherThread MetrixHDWeatherStandalone lookup for ID 15002838
16:19:47.563 [   ] dvb/dvbtime.cpp:401 updateTime [eDVBLocalTimerHandler] Transponder time is 23.10.2015 16:19:45
16:19:47.565 [   ] dvb/dvbtime.cpp:414 updateTime [eDVBLocalTimerHandler] diff is -2
16:19:47.566 [   ] dvb/dvbtime.cpp:417 updateTime [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
16:19:47.587 [   ] dvb/dvbtime.cpp:500 updateTime [eDVBLocalTimerHandler] no RTC available :(
16:19:47.589 [   ] dvb/dvbtime.cpp:507 updateTime [eDVBLocalTimerHandler] time update to 16:19:45
16:19:47.590 [   ] dvb/dvbtime.cpp:510 updateTime [eDVBLocalTimerHandler] m_time_difference is -2
16:19:47.591 [   ] dvb/dvbtime.cpp:514 updateTime [eDVBLocalTimerHandler] set Linux Time
16:19:45.593 [   ] dvb/dvbtime.cpp:525 updateTime [eDVBLocalTimerHandler] time after update is 16:19:45
16:22:29.059 {   } Components/Renderer/MetrixHDWeatherUpdaterStandalone.py:91 getWeatherThread MetrixHDWeatherStandalone lookup for ID 15002838
16:22:29.680 {   } Components/Renderer/MetrixHDWeatherUpdaterStandalone.py:91 getWeatherThread MetrixHDWeatherStandalone lookup for ID 15002838
16:28:57.897 <   > dvb/epgcache.cpp:1981 readData [EPGC] schedule other finished(1445610537)
16:28:57.897 [   ] service/servicedvbrecord.cpp:522 gotNewEvent pcr of eit change: 2b3f1830
16:28:57.902 [   ] dvb/epgcache.cpp:1493 finishEPG [EPGC] stop caching events(1445610537)
16:28:57.905 [   ] dvb/epgcache.cpp:1495 finishEPG [EPGC] next update in 60 min
16:29:27.917 [   ] service/servicedvbrecord.cpp:522 gotNewEvent pcr of eit change: 2b687540
16:29:27.919 [   ] service/servicedvbrecord.cpp:527 gotNewEvent [eDVBServiceRecord] now running: The Starter Wife (2400 seconds)
16:31:21.912 { E } plugin/controllers/SR.py:24 __init__ SerienRecorder plugin not found
16:31:21.914 { E } plugin/controllers/ER.py:23 __init__ ER plugin not found
16:31:21.917 { E } plugin/controllers/base.py:182 render [OpenWebif] page '/favicon.ico' not found
16:32:29.060 {   } Components/Renderer/MetrixHDWeatherUpdaterStandalone.py:91 getWeatherThread MetrixHDWeatherStandalone lookup for ID 15002838
16:32:29.683 {   } Components/Renderer/MetrixHDWeatherUpdaterStandalone.py:91 getWeatherThread MetrixHDWeatherStandalone lookup for ID 15002838
16:32:30.510 {   } Components/NetworkTime.py:42 update_schedule [NTP]: setting E2 unixtime: 1445610750.51
16:32:30.512 {   } Components/NetworkTime.py:43 update_schedule [NTP]: setting E2 realtime: Fri Oct 23 16:32:30 2015
16:32:54.579 [   ] driver/rcinput.cpp:23 handleCode 1 74 1
16:32:54.582 {   } Screens/InfoBarGenerics.py:204 actionA KEY: 116 0 KEY_POWER POWER
16:32:54.584 {   } Components/ActionMap.py:46 action action ->  StandbyActions power
16:32:54.586 {   } Screens/Standby.py:28 Power [Standby] leave standby
16:32:54.611 {   } Screens/Screen.py:150 show [SCREENNAME]  InfoBarSummary
16:32:54.614 {   } Navigation.py:245 playService playing 1:0:19:70:D:85:C00000:0:0:0:
16:32:54.617 { E } Components/config.py:1922 getResolvedKey getResolvedKey config.usage.remote_fallback failed !! (Typo??)
16:32:54.637 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
16:32:54.644 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
16:32:54.648 { E } Tools/Notifications.py:36 RemovePopup RemovePopup, id = ZapError
16:32:54.651 [   ] dvb/dvb.cpp:1132 allocateChannel allocate channel.. 000d:0085
16:32:54.652 [   ] dvb/dvb.cpp:1135 allocateChannel available channel.. 0001:0085
16:32:54.654 [   ] dvb/pmt.cpp:773 tuneExt allocate Channel: res -6
16:32:54.655 [ E ] service/servicedvb.cpp:1212 serviceEvent DVB service failed to tune - error 0
16:32:54.765 {   } skin.py:1029 readSkin [SKIN] processing screen MessageBoxSimple:
16:32:54.792 {   } skin.py:1029 readSkin [SKIN] processing screen MessageBoxSimple_summary:
16:32:54.797 {   } Screens/Screen.py:150 show [SCREENNAME]  ['MessageBoxSimple_summary', 'SimpleSummary']
16:32:54.800 {   } Screens/Screen.py:150 show [SCREENNAME]  ['MessageBoxSimple']
16:32:54.805 [   ] driver/rcinput.cpp:23 handleCode 0 74 1
16:32:54.809 {   } Screens/InfoBarGenerics.py:204 actionA KEY: 116 1 KEY_POWER POWER
16:33:00.000 {   } RecordTimer.py:193 log [TIMER] stop recording
16:33:00.003 [   ] service/servicedvbrecord.cpp:186 stop stop recording!
16:33:00.007 [   ] base/filepush.cpp:438 stop [eFilePushThreadRecorder] stopping thread.
16:33:00.009 [   ] dvb/demux.cpp:576 flush [eDVBRecordFileThread] waiting for aio to complete
16:33:00.010 [   ] dvb/demux.cpp:582 flush [eDVBRecordFileThread] buffer usage histogram (40 buffers of 188 kB)
16:33:00.012 [   ] dvb/demux.cpp:585 flush       0:     27
16:33:00.013 [   ] dvb/demux.cpp:585 flush       1:   3419