PDA

View Full Version : [VU+ Duo] Crash at start of timer recording



pdlee
21-08-13, 21:38
Hi all, I have a problem with my Duo (with the latest Vix build) where the start of some, but not all timer recordings causes the box to crash and restart in a continual loop even when there is only one recording being made. The crash loop continues until the programme has finished when the box restarts without any problems.

Can anyone offer any suggestions as to what the cause of the problem might be please?

Thanks.

Larry-G
21-08-13, 21:42
we would need to see some of the crash logs generated before we can try to help.

pdlee
21-08-13, 22:21
<?xml version="1.0" encoding="utf-8"?>
<openvix>
<enigma2>
<crashdate>Wed Aug 21 21:00:38 2013</crashdate>
<compiledate>Aug 14 2013</compiledate>
<contactemail>vixlogs@world-of-satellite.com</contactemail>
<!-- Please email this crashlog to above address -->
<skin>ViX-Night-HD/skin.xml</skin>
<sourcedate>2013-08-14</sourcedate>
<version>3.0.0</version>
</enigma2>
<image>
<stbmodel>duo</stbmodel>
<kernelversion>
<!-- Cannot allocate memory -->
</kernelversion>
<kernelcmdline>bmem=112M ubi.mtd=0 root=ubi0:rootfs rootfstype=ubifs rw console=ttyS0,115200n8 rootflags=sync</kernelcmdline>
<nimsockets>NIM Socket 0:</nimsockets>
<imageversion>
<![CDATA[
box_type=vuduo
build_type=0
version=3.0
build=720
drivers=20130731
date=20130813224601
comment=ViX
target=9
creator=openViX
url=http://www.vuplus-support.co.uk
catalog=http://www.vuplus-support.co.uk
]]>
</imageversion>
<imageissue>
<![CDATA[
Welcome to OpenViX for %h
openvix 3.0 %h

]]>
</imageissue>
</image>
<software>
<enigma2software>
<!-- Cannot allocate memory -->
</enigma2software>
<vuplussoftware>
<!-- Cannot allocate memory -->
</vuplussoftware>
<gstreamersoftware>
<!-- Cannot allocate memory -->
</gstreamersoftware>
</software>
<crashlogs>
<enigma2crashlog>
<![CDATA[
.780.tv 780
82 entries in Bouquet autobouquet.skyuk.780.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.861.tv 861
21 entries in Bouquet autobouquet.skyuk.861.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.881.tv 881
9 entries in Bouquet autobouquet.skyuk.881.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.889.tv 889
11 entries in Bouquet autobouquet.skyuk.889.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.899.tv 899
2 entries in Bouquet autobouquet.skyuk.899.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.900.tv 900
51 entries in Bouquet autobouquet.skyuk.900.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.950.tv 950
51 entries in Bouquet autobouquet.skyuk.950.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.1000.tv 1000
21 entries in Bouquet autobouquet.skyuk.1000.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.1020.tv 1020
11 entries in Bouquet autobouquet.skyuk.1020.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.1030.tv 1030
6 entries in Bouquet autobouquet.skyuk.1030.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.hd.tv 1035
64 entries in Bouquet autobouquet.skyuk.hd.tv
loading bouquet... /etc/enigma2/autobouquet.skyuk.separator.tv 1090
912 entries in Bouquet autobouquet.skyuk.separator.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.101.tv 2001
240 entries in Bouquet autobouquet.skyie.101.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.240.tv 2240
62 entries in Bouquet autobouquet.skyie.240.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.301.tv 2301
50 entries in Bouquet autobouquet.skyie.301.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.350.tv 2350
52 entries in Bouquet autobouquet.skyie.350.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.401.tv 2401
101 entries in Bouquet autobouquet.skyie.401.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.501.tv 2501
20 entries in Bouquet autobouquet.skyie.501.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.520.tv 2520
61 entries in Bouquet autobouquet.skyie.520.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.580.tv 2580
22 entries in Bouquet autobouquet.skyie.580.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.601.tv 2601
40 entries in Bouquet autobouquet.skyie.601.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.640.tv 2640
61 entries in Bouquet autobouquet.skyie.640.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.700.tv 2700
81 entries in Bouquet autobouquet.skyie.700.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.780.tv 2780
82 entries in Bouquet autobouquet.skyie.780.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.861.tv 2861
21 entries in Bouquet autobouquet.skyie.861.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.881.tv 2881
9 entries in Bouquet autobouquet.skyie.881.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.889.tv 2889
11 entries in Bouquet autobouquet.skyie.889.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.899.tv 2899
2 entries in Bouquet autobouquet.skyie.899.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.900.tv 2900
51 entries in Bouquet autobouquet.skyie.900.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.950.tv 2950
51 entries in Bouquet autobouquet.skyie.950.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.1000.tv 3000
21 entries in Bouquet autobouquet.skyie.1000.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.1020.tv 3020
11 entries in Bouquet autobouquet.skyie.1020.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.1030.tv 3030
6 entries in Bouquet autobouquet.skyie.1030.tv
loading bouquet... /etc/enigma2/autobouquet.skyie.separator.tv 3035
967 entries in Bouquet autobouquet.skyie.separator.tv
loading bouquet... /etc/enigma2/userbouquet.favourites.tv 4001
0 entries in Bouquet userbouquet.favourites.tv
46 entries in Bouquet bouquets.tv
loading bouquet... /etc/enigma2/bouquets.radio 1
loading bouquet... /etc/enigma2/autobouquet.skyuk.main.radio 1
997 entries in Bouquet autobouquet.skyuk.main.radio
loading bouquet... /etc/enigma2/autobouquet.skyie.main.radio 997
997 entries in Bouquet autobouquet.skyie.main.radio
Bouquet load failed.. no path given..
loading bouquet... /etc/enigma2/userbouquet.favourites.radio 1993
0 entries in Bouquet userbouquet.favourites.radio
4 entries in Bouquet bouquets.radio
[Time By]: Transponder
[Ipkg] Added to OPKG destinations: /
[FONT] adding font /usr/share/fonts/Roboto-Bold.ttf...OK (Regular)
[FONT] adding font /usr/share/fonts/Roboto-BoldItalic.ttf...OK (Italic)
[FONT] adding font /usr/share/fonts/Roboto-Black.ttf...OK (Bold)
[FONT] adding font /usr/share/fonts/Roboto-BlackItalic.ttf...OK (Boldit)
[FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (Subtitlefont)
[FONT] adding font /usr/share/fonts/lcd.ttf...OK (LCD)
[FONT] adding font /usr/share/fonts/ae_AlMateen.ttf...OK (Replacement)
[FONT] adding font /usr/share/fonts/tuxtxt.ttf...OK (Console)
[SKIN] loading include: /usr/share/enigma2/ViX-Common/CommonSkin.xml
[FONT] adding font /usr/share/fonts/valis_enigma.ttf...OK (Subs)
[FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (FdLcD)
[iInputDevices] getInputDevices <ERROR: ioctl(EVIOCGNAME): [Errno 25] Inappropriate ioctl for device >
[iInputDevices] getInputDevices <ERROR: ioctl(EVIOCGNAME): [Errno 25] Inappropriate ioctl for device >
Activating keymap: Default (US)
Activating language English (UK)
--> setting scaler_sharpness to: 0000000D
[EPGC Loads] Poller enabled.
[EPGC Save] Poller enabled.
[EPGC] setCacheFile read/write epg data from/to '/media/hdd/epg.dat'
[EPGC] time updated.. start EPG Mainloop
couldn't open /proc/stb/misc/12V_output
setLCDBrightness 25
setLCDBrightness 127
Setting OSD position:0 720 0 576
Setting 3D mode: auto
Setting 3D depth: 50
Setting OSD alpha: 255
[EPGC Save] Poller enabled.
[EPGC Loads] Poller enabled.
FIXME: request for unknown slot
FIXME: request for unknown slot
Plugin Extensions/WebInterface failed to load: No module named plugin
[EPGC] 121099 events read from /media/hdd/epg.dat
--> setting contrast to: 00008000
--> setting saturation to: 00008000
--> setting hue to: 00008000
--> setting brightness to: 00008000
--> setting block_noise_reduction to: 00000000
--> setting mosquito_noise_reduction to: 00000000
--> setting digital_contour_removal to: 00000000
--> setting splitmode to: off
--> setting sharpness to: 00000000
--> setting auto_flesh to: 00000000
--> setting green_boost to: 00000000
--> setting blue_boost to: 00000000
--> setting dynamic_contrast to: 00000000
--> setting color_soace to: Edid(Auto)
--> applying pep values
getModeList for port DVI-PC
remove DVI-PC because of not existing modes
getModeList for port Scart-YPbPr
getModeList for port YPbPr
getModeList for port Scart
getModeList for port DVI
hotplug on dvi
-> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto
-> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto
-> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto
-> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto
setMode - port: DVI mode: 1080i rate: multi
-> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto
[XMLTVImport] autostart (0) occured at 1377115230.15
[AutoTimer] Auto Poll Enabled
[SoftcamManager] AutoStart Enabled
[CI_Assignment] activating ci configs:
[CI_Activate] FOUND 2 CI Slots
[CI_Activate_Config_CI0] no config file found
[CI_Activate_Config_CI0] error parsing xml...
[CI_Activate_Config_CI1] no config file found
[CI_Activate_Config_CI1] error parsing xml...
starting hotplug handler
[Timer] Record RecordTimerEntry(name=24 Hours in A&E, begin=Wed Aug 21 20:57:00 2013, serviceref=1:0:19:52D0:814:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[TIMER] activating state 1
[TIMER] Found enough free space to record
[TIMER] Filename calculated as: '/media/hdd/movie/20130821 2057 - Channel 4 HD - 24 Hours in A&E_003'
recording service: <enigma.eServiceReference; proxy of <Swig Object of type 'eServiceReference *' at 0x1d437e8> >
allocate channel.. 0814:0002
opening frontend 0
(0)tune
RotorCmd ffffffff, lastRotorCmd ffffffff
prepare_sat System 0 Freq 11126500 Pol 1 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0
tuning to 1376 mhz
OURSTATE: tuning
allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:19:52D0:814:2:11A0000:0:0:0:
allocate demux
query epg event id 35201
found event.. store to disc
[TIMER] prepare ok, waiting for begin
[TIMER] Failed to call Trashcan.instance.cleanIfIdle()
[TIMER] Error: 'module' object has no attribute 'instance'
[TIMER] activating state 2
[TIMER] start recording
AddPopup, id = None
[Timer] Record RecordTimerEntry(name=The Imposter, begin=Thu Aug 22 20:57:00 2013, serviceref=1:0:19:52D0:814:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[Timer] Record RecordTimerEntry(name=24 Hours in A&E, begin=Thu Aug 22 22:57:00 2013, serviceref=1:0:19:52D0:814:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=Match of the Day, begin=Sat Aug 24 22:17:00 2013, serviceref=1:0:19:1B1D:802:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=The Sunday Supplement, begin=Sun Aug 25 09:57:00 2013, serviceref=1:0:19:EDA:7D5:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=Dragons' Den, begin=Sun Aug 25 19:57:00 2013, serviceref=1:0:19:1B1C:802:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=24 Hours in A&E, begin=Wed Aug 28 20:57:00 2013, serviceref=1:0:19:52D0:814:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=24 Hours in A&E, begin=Thu Aug 15 23:07:00 2013, serviceref=1:0:19:52D0:814:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=Finding Nemo, begin=Fri Aug 16 11:57:00 2013, serviceref=1:0:19:F3C:7E8:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[Timer] Record RecordTimerEntry(name=A Bug's Life, begin=Sat Aug 17 10:27:00 2013, serviceref=1:0:19:F3C:7E8:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[Timer] Record RecordTimerEntry(name=Match of the Day, begin=Sat Aug 17 22:27:00 2013, serviceref=1:0:19:1B1D:802:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=The Sunday Supplement, begin=Sun Aug 18 09:27:00 2013, serviceref=1:0:19:EDA:7D5:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=Prometheus, begin=Sun Aug 18 13:12:00 2013, serviceref=1:0:19:EE9:7E8:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[Timer] Record RecordTimerEntry(name=Cars, begin=Sun Aug 18 11:12:00 2013, serviceref=1:0:19:F3C:7E8:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[Timer] Record RecordTimerEntry(name=Shrek 2, begin=Sun Aug 18 16:07:00 2013, serviceref=1:0:19:EEA:7E0:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[Timer] Record RecordTimerEntry(name=Dragons' Den, begin=Sun Aug 18 19:57:00 2013, serviceref=1:0:19:1B1C:802:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=Match of the Day 2, begin=Sun Aug 18 22:22:00 2013, serviceref=1:0:19:1B1D:802:2:11A0000:0:0:0:, justplay=0, isAutoTimer=1)
[Timer] Record RecordTimerEntry(name=Boogie Nights, begin=Mon Aug 19 02:27:00 2013, serviceref=1:0:19:F3D:7E0:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[Timer] Record RecordTimerEntry(name=Don't Get Done, Get Dom, begin=Tue Aug 20 07:47:00 2013, serviceref=1:0:1:189E:7FD:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[Timer] Record RecordTimerEntry(name=Monsters, Inc., begin=Tue Aug 20 12:17:00 2013, serviceref=1:0:19:F3C:7E8:2:11A0000:0:0:0:, justplay=0, isAutoTimer=0)
[ImageManager] AutoStart Enabled
[ImageManager] Backup Schedule Disabled at (now=Wed 21 Aug 2013 21:00:30 IST)
[BackupManager] AutoStart Enabled
[BackupManager] Backup Schedule Disabled at (now=Wed 21 Aug 2013 21:00:30 IST)
[AutoBouquetsMaker] AutoStart Enabled
[AutoBouquetsMaker] AutoBouquetsMaker Schedule Enabled at Wed 21 Aug 2013 21:00:30 IST
[AutoBouquetsMaker] AutoBouquetsMaker Time set to Thu 22 Aug 2013 04:30:00 IST (now=Wed 21 Aug 2013 21:00:30 IST)
[XMLTVImport] autostart (0) occured at 1377115230.66
[XMLTVImport] WakeUpTime now set to -1 (now=1377115230)
warning, skin is missing element state in <class 'Screens.PVRState.TimeshiftState'>
RemovePopup, id = ZapError
[Skin] Attribute not implemented: alphatest value: blend
setValue 45
Setvolume: 100 100 (raw)
Setvolume: 0 0 (-1db)
Setvolume: 45 45 (raw)
Setvolume: 35 35 (-1db)
[LogManager] Trim Poll Started
[LogManager] Trash Poll Started
[SEC] set static current limiting
checkTimeshiftRunning
self.switchToLive True
playing 1:0:1:1B64:7DA:2:11A0000:0:0:0:
[Picload] setPara max-X=100 max-Y=60 aspect_ratio=0.000000 cache=1 resize=1 bg=#FF000000
[Picload] decode picture... /media/usb/picon/1_0_1_1B64_7DA_2_11A0000_0_0_0.png
[Picload] setPara max-X=100 max-Y=60 aspect_ratio=0.000000 cache=1 resize=1 bg=#FF000000
[Picload] decode picture... /media/usb/picon/1_0_1_1B64_7DA_2_11A0000_0_0_0.png
__serviceStarted
!!!!!!!!!!!!!!!!!!!!! ptsCleanTimeshiftFolder
self.timeshiftEnabled1 0
TS AUTO START TEST2
__seekableStatusChanged
RemovePopup, id = ZapError
allocate channel.. 07da:0002
available channel.. 0814:0002
opening frontend 1
(1)tune
RotorCmd ffffffff, lastRotorCmd ffffffff
prepare_sat System 0 Freq 11895000 Pol 1 SR 27500000 INV 2 FEC 2 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0
tuning to 1295 mhz
OURSTATE: tuning
allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:1:1B64:7DA:2:11A0000:0:0:0:
allocate demux
KEY: 116 POWER
action -> GlobalActions power_down
KEY: 116 POWER
action -> GlobalActions power_up
KEY: 116 POWER
action -> GlobalActions power_down
KEY: 116 POWER
action -> GlobalActions power_up
KEY: 116 POWER
action -> GlobalActions power_down
KEY: 116 POWER
action -> GlobalActions power_up
child has terminated
pipes closed
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 33(16)
poll: unhandled POLLERR/HUP/NVAL for fd 35(16)
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 40(16)
job Components.Task.Job name=LogManager #tasks=1 completed with [] in None
[SEC] invalidate current switch params
[SEC] set static current limiting
[LogManager] probing folders
[SEC] invalidate current switch params
[SEC] setVoltage 1
[SEC] sleep 10ms
[SEC] setVoltage 1
[SEC] sleep 10ms
[LogManager] found following log's: ['/home/root/logs']
[LogManager] looking in: /home/root/logs
child has terminated
pipes closed
0.0.0.0
192.168
[OpenWebif] loading external plugins...
[OpenWebif] no plugins to load
[OpenWebif] started on 80
[LogManager] /home/root/logs: bytesToRemove -10276887
[OpenWebif] started on 443
poll: unhandled POLLERR/HUP/NVAL for fd 34(16)
job Components.Task.Job name=LogManager #tasks=1 completed with [] in None
set sequence pos 3
executing opkg --add-dest /:/ update
job Components.Task.Job name=OnlineVersionCheck #tasks=1 completed with [] in None
[SEC] update current switch params
[SEC] setTone 1
[SEC] startTuneTimeout 5000
[SEC] sleep 10ms
[EPGC] Saving EPGCache.
[EPGC] store epg to realpath '/media/hdd/epg.dat'
[EPGC] 120611 events written to /media/hdd/epg.dat
[SEC] setFrontend 1
setting frontend 0
[SEC] update current switch params
(0)fe event: status 0, inversion off, m_tuning 1
[SEC] sleep 500ms
[SEC] startTuneTimeout 5000
[SEC] setFrontend 1
setting frontend 1
(1)fe event: status 0, inversion off, m_tuning 1
job Components.Task.Job name=EPG Cache Check #tasks=2 completed with [] in None
[SEC] sleep 500ms
[EPGC] Refreshing EPGCache.
[EPGC] 120611 events read from /media/hdd/epg.dat
[SEC] set dynamic current limiting
Timeout!
[SEC] set dynamic current limiting
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!autostartPermanent Timeshift
stopTimeshiftcheckTimeshiftRunningCallback
answer True
TEST6
TEST7
__seekableStatusChanged
Start timeshift!
Traceback (most recent call last):
File "/usr/lib/enigma2/python/Screens/InfoBarGenerics.py", line 2588, in autostartPermanentTimeshift
File "/usr/lib/enigma2/python/Screens/InfoBarGenerics.py", line 2629, in activatePermanentTimeshift
File "/usr/lib/enigma2/python/mytest.py", line 340

judge
21-08-13, 22:47
Can you update to build 722 & also disable permanent timeshift & let us know if the same happens?

pdlee
22-08-13, 07:02
Hi Judge, I get a "nothing to upgrade" message when I try to update which is why I thought I had the latest build.

I've just changed the "automatically start time shift after" from "2 seconds" to "Disabled" - is this correct?

Thanks.

judge
22-08-13, 10:29
Yes, that will disable timeshift after an E2 restart.
You may have to enable unstable updates to update to 722 though.

pdlee
22-08-13, 14:31
Okay will give that a go. Thanks for your help Judge.

Rob van der Does
22-08-13, 18:22
(de)activating TS takes effect immediately (i.e. after zapping once); no need for a restart. So you can easily switch between TS automated and off. to see if there's any side-effect.