Sun Aug 28 21:45:21 2016 Nov 10 2011 crashlog@dream-multimedia-tv.de newnigma2-HD/skin.xml 2011-11-10 3.2 dm8000 root=/dev/mtdblock3 rootfstype=jffs2 rw console=null NIM Socket 0: url=http://www.newnigma2.to catalog=http://feed.newnigma2.to/stable/3.3.1 ]]> GlobalActions volumeUp Setvolume: 75 75 (raw) Setvolume: 16 16 (-1db) setValue 75 [FanControl2] Vlt:255 Pwm:171 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3540 zielRPM:3650 Temp:49.0 getNextSourceSpan, current offset is 0b8cc644, m_skipmode_m = 0! NO CUESHEET. (0b8cc644, 10485700) action -> GlobalActions volumeDown Setvolume: 70 70 (raw) Setvolume: 19 19 (-1db) setValue 70 getNextSourceSpan, current offset is 0c2cc608, m_skipmode_m = 0! NO CUESHEET. (0c2cc608, 10485700) [FanControl2] Vlt:255 Pwm:176 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3600 zielRPM:3650 Temp:49.0 getNextSourceSpan, current offset is 0cccc5cc, m_skipmode_m = 0! NO CUESHEET. (0cccc5cc, 10485700) action -> GlobalActions volumeUp Setvolume: 75 75 (raw) Setvolume: 16 16 (-1db) setValue 75 action -> GlobalActions volumeDown Setvolume: 70 70 (raw) Setvolume: 19 19 (-1db) setValue 70 [FanControl2] Vlt:255 Pwm:177 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3630 zielRPM:3650 Temp:49.0 nr_read 1730715 nr_write 582934 sum 2313649 prev_sum 2311083 hdd was accessed since previous check! [IDLE] 0 300 False getNextSourceSpan, current offset is 0d6cc590, m_skipmode_m = 0! NO CUESHEET. (0d6cc590, 10485700) [FanControl2] Vlt:255 Pwm:177 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3630 zielRPM:3650 Temp:49.0 action -> GlobalActions volumeUp Setvolume: 75 75 (raw) Setvolume: 16 16 (-1db) setValue 75 action -> GlobalActions volumeDown Setvolume: 70 70 (raw) Setvolume: 19 19 (-1db) setValue 70 getNextSourceSpan, current offset is 0e0cc554, m_skipmode_m = 0! NO CUESHEET. (0e0cc554, 10485700) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 4126061 bytes for cache used [FanControl2] Vlt:255 Pwm:177 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3630 zielRPM:3475 Temp:48.5 getNextSourceSpan, current offset is 0eacc518, m_skipmode_m = 0! NO CUESHEET. (0eacc518, 10485700) It's now Sun Aug 28 21:43:21 2016 [timer.py] next activation: 1472413501 (in 99998 ms) [FanControl2] Vlt:255 Pwm:172 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3540 zielRPM:3475 Temp:48.5 nr_read 1733068 nr_write 582934 sum 2316002 prev_sum 2313649 hdd was accessed since previous check! [IDLE] 0 300 False getNextSourceSpan, current offset is 0f4cc4dc, m_skipmode_m = 0! NO CUESHEET. (0f4cc4dc, 10485700) [FanControl2] Vlt:255 Pwm:171 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3540 zielRPM:3475 Temp:48.5 action: playpauseService action -> InfobarSeekActions playpauseService resolved to PAUSE eDVBServicePlay::pause decoder state: pause, vpid=5401, apid=5402 VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_FREEZE - ok AUDIO_PAUSE - ok getNextSourceSpan, current offset is 0fecc4a0, m_skipmode_m = 0! NO CUESHEET. (0fecc4a0, 10485700) action: playpauseService action -> InfobarSeekActions playpauseService unpause resolved to PLAY eDVBServicePlay::unpause decoder state: play, vpid=5401, apid=5402 VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok action: unPauseService action -> InfobarSeekActions unPauseService unpause action -> InfobarShowHideActions toggleShow action: playpauseService action -> InfobarSeekActions playpauseService resolved to PAUSE eDVBServicePlay::pause decoder state: pause, vpid=5401, apid=5402 VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_FREEZE - ok AUDIO_PAUSE - ok [FanControl2] Vlt:255 Pwm:170 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3540 zielRPM:3650 Temp:49.0 action: playpauseService action -> InfobarSeekActions playpauseService unpause resolved to PLAY eDVBServicePlay::unpause decoder state: play, vpid=5401, apid=5402 VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok action: unPauseService action -> InfobarSeekActions unPauseService unpause action -> InfobarShowHideActions toggleShow getNextSourceSpan, current offset is 108cc464, m_skipmode_m = 0! NO CUESHEET. (108cc464, 10485700) It's now Sun Aug 28 21:43:50 2016 [timer.py] next activation: 1472413480 (in 49997 ms) [FanControl2] Vlt:255 Pwm:175 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3570 zielRPM:3475 Temp:48.5 nr_read 1734915 nr_write 582934 sum 2317849 prev_sum 2316002 hdd was accessed since previous check! [IDLE] 0 300 False getNextSourceSpan, current offset is 112cc428, m_skipmode_m = 0! NO CUESHEET. (112cc428, 10485700) action: unPauseService action -> InfobarSeekActions unPauseService unpause action -> InfobarShowHideActions toggleShow action: unPauseService action -> InfobarSeekActions unPauseService unpause action -> InfobarShowHideActions toggleShow [FanControl2] Vlt:255 Pwm:174 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3570 zielRPM:3475 Temp:48.5 action: unPauseService action -> InfobarSeekActions unPauseService unpause action -> InfobarShowHideActions toggleShow action: unPauseService action -> InfobarSeekActions unPauseService unpause action -> InfobarShowHideActions toggleShow getNextSourceSpan, current offset is 11ccc3ec, m_skipmode_m = 0! NO CUESHEET. (11ccc3ec, 10485700) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 4126061 bytes for cache used [FanControl2] Vlt:255 Pwm:173 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3570 zielRPM:3650 Temp:49.0 getNextSourceSpan, current offset is 126cc3b0, m_skipmode_m = 0! NO CUESHEET. (126cc3b0, 10485700) [FanControl2] Vlt:255 Pwm:174 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3570 zielRPM:3650 Temp:49.0 nr_read 1737334 nr_write 582934 sum 2320268 prev_sum 2317849 hdd was accessed since previous check! [IDLE] 0 300 False getNextSourceSpan, current offset is 130cc374, m_skipmode_m = 0! NO CUESHEET. (130cc374, 10485700) [FanControl2] Vlt:255 Pwm:175 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3600 zielRPM:3475 Temp:48.5 getNextSourceSpan, current offset is 13acc338, m_skipmode_m = 0! NO CUESHEET. (13acc338, 10485700) It's now Sun Aug 28 21:44:40 2016 [TIMER] activating state 1 begin_date: 20160828 2145 service_name: Das Erste HD name: Inspector Mathias - Mord in Wales: Die Tote im See description: Spielfilm Großbritannien 2015 (Hinterland - A Light in the Sky) [TIMER] Filename calculated as: '/hdd/movie/20160828 2145 - Das Erste HD - Inspector Mathias - Mord in Wales_ Die Tote im See' recording service: > record: 0 [add callback] allocate channel.. 03fb:0001:00c00000 opening frontend 0 [eDVBCAService] new channel 0x1b2d3c8! (0)tune [prepare] UnicableTuningWord 0x49fa [prepare] guard_offset 0 RotorCmd ffffffff, lastRotorCmd ffffffff prepare_sat System 1 Freq 11493750 Pol 0 SR 22000000 INV 2 FEC 2 orbpos 192 system 1 modulation 2 pilot 2, rolloff 0 tuning to 1680 mhz OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:19:283D:3FB:1:C00000:0:0:0: allocate demux query epg event id 52735 found event.. store to disc [TIMER] prepare ok, waiting for begin [timer.py] next activation: 1472413500 (in 19957 ms) [SEC] set static current limiting [SEC] invalidate current switch params [SEC] setVoltage 1 [SEC] sleep 200ms [SEC] setVoltage 2 [SEC] setTone 0 [SEC] sleep 20ms [SEC] sendDiseqc: e0105a49fa [SEC] sleep 50ms [SEC] setVoltage 1 [SEC] update current switch params [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 setting frontend 0 [SEC] sleep 500ms (0)fe event: status 0, freq 1680250, inversion off, m_tuning 1 F (0)fe event: status 1, freq 1680250, inversion off, m_tuning 1 N (0)fe event: status 0, freq 1680250, inversion off, m_tuning 2 N (0)fe event: status 1f, freq 1680812, inversion on, m_tuning 3 N OURSTATE: ok [eDVBCAService] channel 0x1b2d3c8 running [eDVBLocalTimerHandler] channel 0x1b2d3c8 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0x1b2d3c8 running stop release channel timer [EPGC] next update in 10 min RECORD service event 6 tuned.. no version filtering 0012: 4e 28 3d 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 [TIMER] record event 2 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 RECORD service event 5 [TIMER] record event 6 [eDVBLocalTimerHandler] diff is 2 [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time [eDVBLocalTimerHandler] update RTC [eDVBLocalTimerHandler] time update to 21:44:42 [eDVBLocalTimerHandler] m_time_difference is 2 [eDVBLocalTimerHandler] set Linux Time [EPGC] start cleanloop [EPGC] stop cleanloop +[EPGC] 4126061 bytes for cache used 1/1 TID 00 done! PATready use pmtpid 13ec for service_id 283d no version filtering 13ec: 02 28 3d 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 05 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 [SEC] set dynamic current limiting + 1/1 TID 02 done! RECORD service event 5 [TIMER] record event 6 [eDVBCAService] new service 1:0:19:283D:3FB:1:C00000:0:0:0: [eDVBCAService] add demux 3 to slot 0 service 1:0:19:283D:3FB:1:C00000:0:0:0: dvb subtitle PID 13f1 with wrong subtitling type (24)... force 0x10!! Rds PID 13f4 detected ? ! ? [eDVBCIInterfaces] gotPMT demux 3 mask 08 prevhash 00000000 [eDVBCAService] send 84 bytes 9f 80 32 50 03 28 3d 07 00 13 01 81 08 00 c0 00 00 03 fb 00 01 82 02 08 03 84 02 13 ec 05 04 92 00 00 0c 04 98 00 00 0b 08 7b 00 00 1b 13 ed 00 00 03 13 ee 00 00 03 13 ef 00 00 06 13 f0 00 00 06 13 f1 00 00 06 13 f2 00 00 06 13 f4 00 00 0b 14 34 00 00 doing version filtering 13ec: 02 28 3d 07 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 sdt update done! -+ 1/2 TID 4e ++ 2/2 TID 4e done! [eDVBServiceRecord] now running: Tatort: HAL (5400 seconds) [TIMER] record event 9 doing version filtering 0012: 4e 28 3d 3f 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [FanControl2] Vlt:255 Pwm:170 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3510 zielRPM:3475 Temp:48.5 getNextSourceSpan, current offset is 144cc2fc, m_skipmode_m = 0! NO CUESHEET. (144cc2fc, 10485700) getNextSourceSpan, current offset is 14ecc2c0, m_skipmode_m = 0! NO CUESHEET. (14ecc2c0, 10485700) [FanControl2] Vlt:255 Pwm:169 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3480 zielRPM:3475 Temp:48.5 nr_read 1739756 nr_write 582938 sum 2322694 prev_sum 2320268 hdd was accessed since previous check! [IDLE] 0 300 False getNextSourceSpan, current offset is 158cc284, m_skipmode_m = 0! NO CUESHEET. (158cc284, 10485700) It's now Sun Aug 28 21:45:02 2016 [TIMER] activating state 2 [TIMER] start recording [LEDControl] __RecordableServiceStart [LEDControl] blinkOnRecord_start [TIMER] record event 0 AddPopup, id = None Recording to /hdd/movie/20160828 2145 - Das Erste HD - Inspector Mathias - Mord in Wales_ Die Tote im See.ts... start recording... RECORD: have 1 video stream(s) (13ed), and 3 audio stream(s) (13ee, 13ef, 13f2) (13f1), and the pcr pid is 13ed, and the text pid is 13f0 ADD PID: 0000 ADD PID: 13ec ADD PID: 13ed ADD PID: 13ee ADD PID: 13ef ADD PID: 13f0 ADD PID: 13f1 ADD PID: 13f2 before: 1 after: 1 setIoPrio realtime level 7 ok FILEPUSH THREAD START [TIMER] record event 4 EMC: [spRC] REC START for: 20160828 2145 - Das Erste HD - Inspector Mathias - Mord in Wales_ Die Tote im See next real activation is Sun Aug 28 21:59:40 2016 [timer.py] next activation: 1472413602 (in 99820 ms) It's now Sun Aug 28 21:45:03 2016 [timer.py] next activation: 1472413603 (in 99983 ms) Timeout! [FanControl2] Vlt:255 Pwm:169 Fan:4pin - BoxOn HDDon REC [FanControl2] aktuellRPM:3510 zielRPM:3475 Temp:48.5 EMC: [MC] LOAD PATH: /hdd/movie/ action -> InfobarMenuActions mainMenu loading mainmenu XML... TimerEdit TimerEditList PluginBrowser PluginBrowser allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! ERROR reading PES (fd=48) - Value too large for defined data type poll: unhandled POLLERR/HUP/NVAL for fd 48(8) +- 1/2 TID 4e ++ 2/2 TID 4e done! pcr of eit change: 1471f2ad6 [eDVBServiceRecord] now running: Inspector Mathias - Mord in Wales: Die Tote im See (5400 seconds) [TIMER] record event 9 doing version filtering 0012: 4e 28 3d 01 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 action -> MenuActions menu action -> InfobarMenuActions mainMenu loading mainmenu XML... TimerEdit TimerEditList PluginBrowser PluginBrowser allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! action -> OkCancelActions ok okbuttonClick EMPTY: 0 ENABLE: 1 DISABLE: 2 CLEANUP: 3 DELETE: 4 key_red_choice: 0 getNextSourceSpan, current offset is 162cc248, m_skipmode_m = 0! NO CUESHEET. (162cc248, 10485700) action -> ShortcutActions red action -> MsgBoxActions ok [Timer] Remove time changed ProcessRepeated It's now Sun Aug 28 21:45:13 2016 [TIMER] activating state 3 [TIMER] stop recording stop recording! stopping thread. FILEPUSH THREAD STOP thread joined 0 loading streaminfo for /hdd/movie/20160828 2145 - Das Erste HD - Inspector Mathias - Mord in Wales_ Die Tote im See.ts fixed up 1471f2ad6 to 71469 (offset 0) [TIMER] record event 5 [LEDControl] blinkOnRecord_stop [TIMER] record event 1 [remove callback] [eDVBCAService] free slot 0 demux 3 for service 1:0:19:283D:3FB:1:C00000:0:0:0: [eDVBCAService] free service 1:0:19:283D:3FB:1:C00000:0:0:0: start release channel timer EMC: [spRC] REC END for: 20160828 2145 - Das Erste HD - Inspector Mathias - Mord in Wales_ Die Tote im See next real activation is Sun Aug 28 21:59:40 2016 [timer.py] next activation: 1472413613 (in 99694 ms) state: 3 in processed: True in running: False action -> OkCancelActions cancel [FanControl2] Vlt:255 Pwm:168 Fan:4pin - BoxOn HDDon [FanControl2] aktuellRPM:3510 zielRPM:3650 Temp:49.0 action -> OkCancelActions cancel release cached channel (timer timeout) [eDVBCAService] remove channel 0x1b2d3c8 [eDVBLocalTimerHandler] remove channel 0x1b2d3c8 [eEPGCache] remove channel 0x1b2d3c8 stop release channel timer EMC: [MC] LOAD PATH: /hdd/movie/ action -> InfobarMovieListActions movieList action -> InfobarMovieListActions movieList ERROR reading PES (fd=48) - Value too large for defined data type poll: unhandled POLLERR/HUP/NVAL for fd 48(8) getNextSourceSpan, current offset is 16ccc20c, m_skipmode_m = 0! NO CUESHEET. (16ccc20c, 10485700) close frontend 0 [prepareClose] running mainloop set sequence pos 3 set sequence pos 5 [SEC] setVoltage 1 [SEC] sleep 200ms [SEC] setVoltage 4 [SEC] sleep 20ms [SEC] sendDiseqc: e0105a4000 [SEC] sleep 50ms [SEC] setVoltage 1 [SEC] delayed close frontend close frontend 0 action -> PluginMovieSelectionActions EMCEXIT action -> PluginMovieSelectionActions EMCEXIT Traceback (most recent call last): File "/usr/lib/enigma2/python/mytest.py", line 195, in processDelay callback = self.current_dialog.callback AttributeError: 'EMCSelection' object has no attribute 'callback' (PyObject_CallObject(>,()) failed) ]]>