Thu Nov 21 17:23:48 2013 Oct 1 2013 crashlog@dream-multimedia-tv.de newnigma2-HD/skin.xml 2013-10-01 tarball tarball-20131001-0-gc2bc732 4.0.0 dm7020hd ubi.mtd=root root=ubi0:rootfs rootfstype=ubifs rw console=ttyS0,115200 debug bmem=192M@64M url=http://www.newnigma2.to catalog=http://feed.newnigma2.to/stable/4.0 ]]> ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 [TIMER] record time changed, start prepare is now: Thu Oct 17 20:14:40 2013 sanitycheck check checkTimerlist recording service: 1:0:1:2F08:441:1:C00000:0:0:0: record: 0 [Timer] Record ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 It's now Thu Nov 21 17:10:28 2013 [timer.py] next activation: 1385050328 (in 99411 ms) [TeleText] ----- sessionstart(<__main__.Session instance at 0x1970148>) ----- [TeleText] [favorites] reading accel 496000 bytes accel memstat: used=0 kB, free 12784 kB, s 0 kB accel memory: 0 Looking for embedded skin [TeleText] __layoutFinished [ResourceManager] adding: NetworkAdapterSelection [ResourceManager] adding: AdapterSetup [CrashlogAutoSubmit] - autostart [CrashlogAutoSubmit] - not starting CrashlogAutoSubmit Looking for embedded skin [InstallerUpdateCheck] timer changed [InstallerUpdateCheck] next check at Thu Nov 28 17:10:29 2013 [InstallerUpdateCheck] Check... [CamdresetEveryDay] next reset: Thu Nov 21 23:45:28 2013 No skin to read... Looking for embedded skin [Toplevel.importExternalModules] Imported external module: Example [Toplevel.importExternalModules] Could NOT import external module: EPGRefresh [Toplevel.importExternalModules] Exception Caught No module named EPGRefresh.EPGRefreshResource [HTTPRootResource}.__init__ [Webinterface] started on 0.0.0.0:80 auth=False ssl=False [WebInterface.registerBonjourService] No module named Bonjour.Bonjour [HTTPRootResource}.__init__ [Webinterface] started on 0.0.0.0:443 auth=True ssl=True [WebInterface.registerBonjourService] No module named Bonjour.Bonjour [EPGC] set outdated epg timespan to 0 hours... [EPGC] set cache timespan to 28 days! [EPGC] setCacheFile read/write epg data from/to '/media/hdd/epg.dat' [EPGC] time updated.. start EPG Mainloop before: 1 after: 1 not showing fine-tuning wizard, config variable doesn't exist showtestcard is false Looking for embedded skin setValue 65 cannot open /proc/stb/audio/j1_mute(No such file or directory) Setvolume: 100 100 (raw) Setvolume: 0 0 (-1db) Setvolume: 65 65 (raw) Setvolume: 23 23 (-1db) [prepareClose] running mainloop 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! reused converter! RemovePopup, id = ZapError [HbbTV] connecting callbacks 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! 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! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! reused converter! allocating new converter! allocating new converter! allocating new converter! CISlot 0 what 2 CISlot 1 what 2 child has terminated pipes closed [ePopen] command: route -n | grep eth0 child has terminated pipes closed child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 41(16) poll: unhandled POLLERR/HUP/NVAL for fd 43(16) poll: unhandled POLLERR/HUP/NVAL for fd 46(16) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 56(16) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 65(16) [SEC] lock 0 playing 1:0:1:1485:40D:1:C00000:0:0:0: realpath /etc/enigma2/virtsubmapping.txt: No such file or directory can't open /etc/enigma2/virtsubmapping.txt: No such file or directory [TeleText] service started not pauseable. RemovePopup, id = ZapError allocate channel.. 040d:0001:00c00000 opening frontend 0 [eDVBCAService] new channel 0x1ae68e0! (0)tune RotorCmd ffffffff, lastRotorCmd ffffffff prepare_sat System 0 Freq 11023250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 192 system 0 modulation 1 pilot 2, rolloff 0 tuning to 1273 mhz OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:1485:40D:1:C00000:0:0:0: recheckPMTHAndlers() recheck 0x1ae1d3c 1:0:1:1485:40D:1:C00000:0:0:0: check Slot 0 check Slot 1 allocate demux [HbbTV].aitInvalidated child has terminated pipes closed 0.0.0.0 169.254 192.168 nameservers: [[195, 34, 133, 21], [212, 186, 211, 21]] read configured interface: {'lo': {'dhcp': False}, 'eth0': {'dhcp': True}} self.ifaces after loading: {'eth0': {'preup': False, 'predown': False, 'ip': [192, 168, 0, 11], 'up': True, 'dns-nameservers': False, 'broadcast': False, 'mac': '00:09:34:2d:55:19', 'dhcp': True, 'bcast': [192, 168, 0, 255], 'netmask': [255, 255, 255, 0], 'gateway': [192, 168, 0, 1]}} poll: unhandled POLLERR/HUP/NVAL for fd 66(16) [SEC] delayed close frontend close frontend 1 [SEC] lock 0 [SEC] unlock [SEC] set static current limiting [SEC] invalidate current switch params [SEC] setVoltage 2 (0)setVoltage 18V [SEC] sleep 10ms [SEC] update current switch params [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (0)setting frontend (0)fe event: status 0, freq 1273250, inversion off, m_tuning 1 F [SEC] unlock [SEC] sleep 500ms (0)fe event: status 1, freq 1273250, inversion off, m_tuning 1 N [ePopen] command: ping -c 1 82.149.226.170 [ePopen] command: ping -c 1 193.99.144.85 [ePopen] command: ping -c 1 74.125.39.147 (0)fe event: status 1f, freq 1272867, inversion off, m_tuning 2 N OURSTATE: ok [eDVBCAService] channel 0x1ae68e0 running [eDVBLocalTimeHandler] channel 0x1ae68e0 running [eEPGCache] channel 0x1ae68e0 running stop release channel timer [EPGC] next update in 2 sec no version filtering 0012: 4e 14 85 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 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 eventNewProgramInfo 0 0 have 1 video stream(s) (0205), and 1 audio stream(s) (002f), and the pcr pid is 0205, and the text pid is ffffffff allocate demux disable teletext subtitles PES filter: DMX_SET_PES_FILTER - Invalid argument decoder state: play, vpid=517, apid=47 DMX_SET_PES_FILTER(0x205) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x2f) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux3 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x205) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok AUDIO_CHANNEL_SELECT(0) - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID -1 DEMUX 3 child has terminated pipes closed child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 43(16) poll: unhandled POLLERR/HUP/NVAL for fd 52(16) + 1/1 TID 00 done! PATready use pmtpid 08cd for service_id 1485 no version filtering 08cd: 02 14 85 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 25 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 VIDEO_GET_EVENT - ok [SEC] set dynamic current limiting + 1/1 TID 02 done! [eDVBCAService] new service 1:0:1:1485:40D:1:C00000:0:0:0: [eDVBCAService] add demux 3 to slot 0 service 1:0:1:1485:40D:1:C00000:0:0:0: demux 3 mask 08 prevhash 00000000 [eDVBCAService] send 109 bytes 9f 80 32 69 03 14 85 27 00 59 01 81 08 00 c0 00 00 04 0d 00 01 82 02 08 03 84 02 08 cd 09 04 17 02 e8 35 09 04 18 33 e8 3f 09 04 09 c4 e8 49 09 04 09 8c e8 53 09 04 0d 95 eb d9 09 04 06 48 eb ed 09 04 0d 05 eb c5 09 07 18 17 e7 0b 02 00 6a 09 11 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 2f 75 04 00 2f 00 00 02 02 05 00 00 recheckPMTHAndlers() recheck 0x1ae1d3c 1:0:1:1485:40D:1:C00000:0:0:0: check Slot 0 check Slot 1 [eDVBCIInterfaces] gotPMT eventNewProgramInfo 0 0 have 1 video stream(s) (0205), and 1 audio stream(s) (002f), and the pcr pid is 0205, and the text pid is ffffffff PES filter: DMX_SET_PES_FILTER - Invalid argument decoder state: play, vpid=517, apid=47 VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok [TeleText] serviceInfoChanged [TeleText] TXT PID -1 DEMUX 3 doing version filtering 08cd: 02 14 85 27 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 VIDEO_GET_EVENT - ok [ExtendedInfoBar] CAIDs: ['0100', '0648', '098C', '09C4', '0D05', '0D95', '1702', '1817', '1833'] [ExtendedInfoBar] SID: 1485 +- 1/2 TID 4e ++ 2/2 TID 4e done! doing version filtering 0012: 4e 14 85 13 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 sdt update done! child has terminated pipes closed [InstallerUpdateCheck] No updates available [InstallerUpdateCheck] next check at Thu Nov 28 17:10:33 2013 poll: unhandled POLLERR/HUP/NVAL for fd 41(16) [EPGC] start caching events(1385050234) VIDEO_GET_EVENT - ok VIDEO_GET_EVENT - ok child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 79(16) [EPGC] abort non avail schedule other reading [EPGC] abort non avail viasat reading [EPGC] nownext finished(1385050241) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 61(16) [EPGC] schedule finished(1385050244) [EPGC] stop caching events(1385050244) [EPGC] next update in 60 min no ancillary data in audio stream... abort radiotext pes parser nr_read 5064 nr_write 818 sum 5882 prev_sum 0 hdd was accessed since previous check! [IDLE] 0 300 False nr_read 5064 nr_write 824 sum 5888 prev_sum 5882 hdd was accessed since previous check! [IDLE] 0 300 False nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 30.0001950264 300 False It's now Thu Nov 21 17:12:07 2013 [timer.py] next activation: 1385050428 (in 99999 ms) It's now Thu Nov 21 17:12:08 2013 [timer.py] next activation: 1385050428 (in 99997 ms) nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 60.0003709793 300 False nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 90.0004789829 300 False nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 120.000262022 300 False It's now Thu Nov 21 17:13:48 2013 [timer.py] next activation: 1385050528 (in 99999 ms) [timer.py] timewarp - re-evaluating all processed timers. ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 It's now Thu Nov 21 17:13:48 2013 [timer.py] next activation: 1385050528 (in 99990 ms) nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 150.000030041 300 False nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 180.000216007 300 False nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 210.000671148 300 False It's now Thu Nov 21 17:15:27 2013 [timer.py] next activation: 1385050628 (in 99999 ms) It's now Thu Nov 21 17:15:28 2013 [timer.py] next activation: 1385050628 (in 99997 ms) nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 239.999979019 300 False nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 270.000365973 300 False nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 300.000381947 300 False [ePopen] command: ('hdparm', 'hdparm', '-y', '/dev/sda') child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 41(16) nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 330.000060081 300 True It's now Thu Nov 21 17:17:08 2013 [timer.py] next activation: 1385050728 (in 99998 ms) [timer.py] timewarp - re-evaluating all processed timers. ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 It's now Thu Nov 21 17:17:08 2013 [timer.py] next activation: 1385050728 (in 99990 ms) nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 360.000914097 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 390.000828028 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 420.000791073 300 True It's now Thu Nov 21 17:18:47 2013 [timer.py] next activation: 1385050828 (in 99999 ms) [timer.py] timewarp - re-evaluating all processed timers. ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 It's now Thu Nov 21 17:18:48 2013 [timer.py] next activation: 1385050828 (in 99991 ms) nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 450.00061202 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 480.000512123 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 510.000296116 300 True It's now Thu Nov 21 17:20:27 2013 [timer.py] next activation: 1385050928 (in 99999 ms) It's now Thu Nov 21 17:20:28 2013 [timer.py] next activation: 1385050928 (in 99997 ms) nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 540.000108004 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 570.000164032 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 600.000498056 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 630.000726938 300 True It's now Thu Nov 21 17:22:08 2013 [timer.py] next activation: 1385051028 (in 99998 ms) [timer.py] timewarp - re-evaluating all processed timers. It's now Mon Oct 29 08:41:36 2012 [timer.py] next activation: 1385051028 (in 99997 ms) nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 660.000761032 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 690.000298977 300 True nr_read 5064 nr_write 824 sum 5888 prev_sum 5888 hdd IDLE! [IDLE] 720.000574112 300 True It's now Thu Nov 21 17:23:47 2013 [timer.py] next activation: 1385051128 (in 99999 ms) It's now Thu Nov 21 17:23:48 2013 [timer.py] next activation: 1351496696 (in -32484784482 ms) Traceback (most recent call last): File "/usr/lib/enigma2/python/timer.py", line 235, in calcNextActivation self.setNextActivation(min) File "/usr/lib/enigma2/python/timer.py", line 208, in setNextActivation self.timer.start(delay, 1) OverflowError: Python int too large to convert to C long (PyObject_CallObject(>,()) failed) ]]>