joeuser
13.09.2015, 12:58
I had a crash this morning. I started playing back a recording, jumped ahead a few times, then pressed the green button to change the aspect ratio and immediately it crashed. Here is the log:
<?xml version="1.0" encoding="utf-8"?>
<HDMU>
<enigma2>
<crashdate>Sun Sep 13 12:03:30 2015</crashdate>
<compiledate>Sep 9 2015</compiledate>
<skin>skin.xml</skin>
<sourcedate>Sep 9 2015</sourcedate>
<rev>
<=!=[=C=D=A=T=A=[
-----====== HDMU 14084 enigma2 Git 3909 ======-----
]=]=>
</rev>
<freespace>
<=!=[=C=D=A=T=A=[
Filesystem 1K-blocks Used Available Use% Mounted on
/dev/root 2884283616 1214665120 1523105248 44% /
tmpfs 58596 0 58596 0% /ram
/dev/root 2884283616 1214665120 1523105248 44% /dev.static
tmpfs 58596 0 58596 0% /dev
tmpfs 58596 0 58596 0% /dev/shm
tmpfs 58596 12 58584 0% /tmp
192.168.1.11:/alien2/share/
1465136000 1460161408 4974592 100% /media/net/nfs
]=]=>
</freespace>
<freeram>
<=!=[=C=D=A=T=A=[
MemTotal: 117196 kB
MemFree: 2320 kB
Buffers: 0 kB
Cached: 37760 kB
SwapCached: 0 kB
Active: 44460 kB
Inactive: 55956 kB
Active(anon): 31296 kB
Inactive(anon): 31372 kB
Active(file): 13164 kB
Inactive(file): 24584 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 0 kB
SwapFree: 0 kB
Dirty: 0 kB
Writeback: 0 kB
AnonPages: 62684 kB
Mapped: 11792 kB
Shmem: 12 kB
Slab: 5984 kB
SReclaimable: 1568 kB
SUnreclaim: 4416 kB
KernelStack: 656 kB
PageTables: 372 kB
Quicklists: 196 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 58596 kB
Committed_AS: 140520 kB
VmallocTotal: 507824 kB
VmallocUsed: 5840 kB
VmallocChunk: 473008 kB
]=]=>
</freeram>
<version>4</version>
</enigma2>
<image>
<stbmodel>spark7162</stbmodel>
<kernelversion>
<=!=[=C=D=A=T=A=[
Linux SPARK7162 2.6.32.61_stm24_0217 #1 PREEMPT Wed Sep 9 19:40:17 CEST 2015 sh4 GNU/Linux
]=]=>
</kernelversion>
<kernelcmdline>console=ttyAS0,115200 rw root=/dev/nfs nfsroot=192.168.1.1:/alien2/hdmu,nfsvers=3,rsize=16384,wsize=65536,udp init=/bin/devinit coprocessor_mem=4m@0x40000000,4m@0x40400000 printk=1 ip=192.168.1.11:192.168.1.1:192.168.1.1:255.255.25 5.0:SPARK7162:eth0:off stmmaceth=msglvl:0,phyaddr:2,watchdog:5000 loglevel=6 nfsrootdebug nwhwconf=device:eth0,hwaddr:30:00:0A:00:30:60 Manufacture=Spark-Project STB_ID=0C:00:0A:00:00:30:60</kernelcmdline>
<nimsockets>NIM Socket 0:</nimsockets>
<imageversion>
<=!=[=C=D=A=T=A=[
version=0350200905300000
]=]=>
</imageversion>
<imageissue>
<!-- No such file or directory -->
</imageissue>
</image>
<crashlogs>
<enigma2crashlog>
<=!=[=C=D=A=T=A=[
.089985> [eDVBLocalTimerHandler] time update to 09:38:27
< 59716.090126> [eDVBLocalTimerHandler] m_time_difference is -1
< 59716.090263> [eDVBLocalTimerHandler] set Linux Time
< 59716.091331> [eDVBChannel] getDemux cap=00
[__evUpdatedEventInfo]
< 60709.895471> [eDVBServicePlay] timeshift
< 60709.896299> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 60709.930012> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 60709.930678> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 61516.106509> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 61516.107081> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 61531.106579> [eDVBLocalTimerHandler] diff is -1
< 61531.106790> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 61531.107268> [eDVBLocalTimerHandler] no RTC available :(
< 61531.107470> [eDVBLocalTimerHandler] time update to 10:08:42
< 61531.107574> [eDVBLocalTimerHandler] m_time_difference is -1
< 61531.107662> [eDVBLocalTimerHandler] set Linux Time
< 61531.115596> [eDVBChannel] getDemux cap=00
< 61992.519137> [eEPGCache] start caching events(1442132184)
< 61992.519418> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 61992.520154> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 61992.520882> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 62004.153365> [eEPGCache] nownext finished(1442132196)
< 62022.532947> [eEPGCache] schedule finished(1442132214)
< 62070.573124> [convertDVBUTF8] reserved 24
< 62089.541649> [eEPGCache] schedule other finished(1442132281)
< 62089.542753> [eEPGCache] stop caching events(1442132281)
< 62089.542936> [eEPGCache] next update in 60 min
[__evUpdatedEventInfo]
< 62509.956389> [eDVBServicePlay] timeshift
< 62509.957186> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 62510.000964> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 62510.001415> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 63331.128636> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 63331.129044> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 63346.112730> [eDVBLocalTimerHandler] diff is -1
< 63346.113169> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 63346.113722> [eDVBLocalTimerHandler] no RTC available :(
< 63346.113899> [eDVBLocalTimerHandler] time update to 10:38:57
< 63346.113997> [eDVBLocalTimerHandler] m_time_difference is -1
< 63346.114085> [eDVBLocalTimerHandler] set Linux Time
< 63346.115049> [eDVBChannel] getDemux cap=00
< 65146.130991> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 65146.131336> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 65161.127213> [eDVBLocalTimerHandler] diff is -1
< 65161.127488> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 65161.128149> [eDVBLocalTimerHandler] no RTC available :(
< 65161.128378> [eDVBLocalTimerHandler] time update to 11:09:12
< 65161.128502> [eDVBLocalTimerHandler] m_time_difference is -1
< 65161.128610> [eDVBLocalTimerHandler] set Linux Time
< 65161.129677> [eDVBChannel] getDemux cap=00
< 65689.644097> [eEPGCache] start caching events(1442135881)
< 65689.644378> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 65689.645112> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 65689.645844> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 65703.638137> [eEPGCache] nownext finished(1442135895)
< 65719.753688> [eEPGCache] schedule finished(1442135911)
< 65786.677790> [eEPGCache] schedule other finished(1442135978)
< 65786.678651> [eEPGCache] stop caching events(1442135978)
< 65786.678805> [eEPGCache] next update in 60 min
< 66961.145076> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 66961.145429> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 66976.129139> [eDVBLocalTimerHandler] diff is -1
< 66976.129349> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 66976.129837> [eDVBLocalTimerHandler] no RTC available :(
< 66976.130039> [eDVBLocalTimerHandler] time update to 11:39:27
< 66976.130140> [eDVBLocalTimerHandler] m_time_difference is -1
< 66976.130228> [eDVBLocalTimerHandler] set Linux Time
< 66976.131166> [eDVBChannel] getDemux cap=00
[__evUpdatedEventInfo]
< 67310.215686> [eDVBServicePlay] timeshift
< 67310.217894> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 67310.252853> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 67310.253179> [eDVBSectionReader] DMX_SET_FILTER pid=18
[__evUpdatedEventInfo]
< 68210.231927> [eDVBServicePlay] timeshift
< 68210.232733> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 68210.272042> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 68210.272371> [eDVBSectionReader] DMX_SET_FILTER pid=18
[__evUpdatedEventInfo]
< 68235.325830> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 68235.326155> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 68358.250287> [eDVBServicePlay] timeshift
< 68358.254385> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 68362.607795> [eDVBVolumecontrol] Setvolume: raw: 50 50, -1db: 32 32
< 68362.608116> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 50
< 68363.379851> [eDVBVolumecontrol] Setvolume: raw: 55 55, -1db: 29 29
< 68363.380171> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 55
< 68363.727203> [eDVBVolumecontrol] Setvolume: raw: 60 60, -1db: 26 26
< 68363.727522> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 60
< 68364.036179> [eDVBVolumecontrol] Setvolume: raw: 65 65, -1db: 23 23
< 68364.036512> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 65
< 68364.327810> [eDVBVolumecontrol] Setvolume: raw: 70 70, -1db: 19 19
< 68364.328139> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 70
< 68364.516007> [eDVBVolumecontrol] Setvolume: raw: 75 75, -1db: 16 16
< 68364.516585> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 75
< 68364.848112> [eDVBVolumecontrol] Setvolume: raw: 80 80, -1db: 13 13
< 68364.848445> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 80
< 68365.087986> [eDVBVolumecontrol] Setvolume: raw: 85 85, -1db: 10 10
< 68365.088313> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 85
< 68365.332026> [eDVBVolumecontrol] Setvolume: raw: 90 90, -1db: 7 7
< 68365.332615> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 90
< 68367.144304> [eDVBServicePlay] timeshift
< 68367.145941> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 68382.799465> [eDVBServicePlay] timeshift
< 68382.800554> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 68382.822048> [eDVBServicePlay] timeshift
< 68382.822905> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
warning, skin is missing element chosenletter in <class 'Screens.MovieSelection.MovieSelection'>
warning, skin is missing element movie_sort in <class 'Screens.MovieSelection.MovieSelection'>
[Skin] Attribute not implemented: RecordingColor value: #00f23d21
[Skin] Attribute not implemented: FinishedColor value: #0065C416
[Skin] Attribute not implemented: WatchingColor value: #00bab329
[Skin] Attribute not implemented: UnwatchedColor value: #00ffffff
warning, skin is missing element movie_off in <class 'Screens.MovieSelection.MovieSelection'>
HOTPLUG connection!
< 68388.147092> [gRC] main thread is non-idle! display spinner!
setPixmapNum(1) failed! defined pixmaps: []
setPixmapNum(7) failed! defined pixmaps: []
hotplug: ACTION=add
complete ACTION=add
HOTPLUG connection lost!
hotplug: {'ACTION': 'add', 'PHYSDEVPATH': '-', 'DEVPATH': '/devices/virtual/bdi/0:17'}
couldn't read model: [Errno 2] No such file or directory: '/sys/block/0:1/device/model'
< 68390.263643> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file.ts
< 68390.314073> [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 90052, 6891229114 (76569s), fixup to: 90052, 24344 (0s)
< 68390.356566> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file2.ts
< 68390.396955> [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 27448, 592386653 (6582s), fixup to: 27448, 13071 (0s)
< 68390.495435> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file3.ts
< 68390.497664> [eDVBTSTools] getPTS read error
< 68390.497867> [eDVBTSTools] calcEnd [@ML] m_streaminfo.getLastFrame failed, fallback
< 68390.629893> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file4.ts
< 68390.630507> [eDVBTSTools] getPTS read error
< 68390.630685> [eDVBTSTools] calcEnd [@ML] m_streaminfo.getLastFrame failed, fallback
< 68398.114196> [eDVBServicePlay] timeshift
< 68398.116640> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
[SKIN] Parsing embedded skin <embedded-in-'Screensaver'>
not pauseable.
RemovePopup, id = ZapError
playing 1:0:0:0:0:0:0:0:0:0:/hdd/movie/file2.ts
< 68399.224656> [eDVBCAService] free slot 1 demux 0 for service 1:0:1:1D5F:C3B4:7E:460000:0:0:0:
< 68399.229737> [eDVBCAService] free service 1:0:1:1D5F:C3B4:7E:460000:0:0:0:
< 68399.415743> [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
< 68399.416002> [eDVBPCR0] DEMUX_STOP ok
< 68399.416413> [eDVBPCR0] destroy
< 68399.416668> [eDVBVideo0] DEMUX_STOP ok
< 68399.417367> [eDVBVideo0] VIDEO_STOP ok
< 68399.532790> [eDVBVideo0] destroy
< 68399.533266> [eDVBAudio0] AUDIO_STOP ok
< 68399.703452> [eDVBAudio0] DEMUX_STOP ok
< 68399.704303> [eDVBAudio0] AUDIO_CONTINUE ok
< 68399.704577> [eDVBAudio0] destroy
< 68399.733565> [eDVBLocalTimerHandler] remove channel 0x2d962220
< 68399.734420> [eEPGCache] remove channel 0x2d962220
getResolvedKey config.usage.remote_fallback failed !! (Typo??)
< 68399.759368> [eDVBServicePMTHandler] alloc PVR
< 68399.759916> [eDVBChannel] getDemux cap=01
< 68399.760228> [eDVBResourceManager] allocate demux cap=01
< 68399.760394> [eDVBResourceManager] allocating demux adapter=0, demux=3, source=-1 fesource=-1
< 68399.760578> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68399.760835> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file2.ts
< 68399.830495> [eDVBServicePlay] cuts file has 2 entries
< 68399.830849> [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 27448, 592386653 (6582s), fixup to: 27448, 13071 (0s)
< 68399.843824> [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
< 68399.844055> [eDVBServicePlay] have 1 video stream(s) (096e), and 1 audio stream(s) (0d56), and the pcr pid is 096e, and the text pid is ffffffff
< 68399.847204> [setIoPrio] best-effort level 0 ok
< 68399.855758> [eFilePushThread] START thread
< 68399.877114> [eTSMPEGDecoder] decoder state: play, vpid=096e, apid=0d56
ok
< 68399.877789> [eDVBAudio0] AUDIO_SET_BYPASS bypass=1 ok
< 68399.877968> [eDVBAudio0] AUDIO_PLAY ok
< 68399.911592> [eDVBVideo] Video Device: /dev/dvb/adapter0/video0
< 68399.912472> [eDVBVideo] demux device: /dev/dvb/adapter0/demux3
< 68399.912840> [eDVBVideo0] VIDEO_SET_STREAMTYPE 0 - ok
< 68399.913187> [eDVBVideo0] DMX_SET_PES_FILTER pid=0x096e ok
< 68399.914116> [eDVBVideo0] VIDEO_PLAY ok
< 68400.015604> [eDVBVideo0] VIDEO_CONTINUE ok
< 68400.016107> [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
< 68400.016567> [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
< 68400.017042> [eDVBAudio0] AUDIO_CONTINUE ok
< 68400.017722> [eDVBTeletextParser] Starting!
< 68400.017885> [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
< 68400.018080> [eDVBPESReader] Created. Opening demux
< 68400.018217> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68400.024943> [eDVBTeletextParser] created teletext subtitle PES reader!
< 68400.025277> [eDVBPESReader] Created. Opening demux
< 68400.025418> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68400.025905> [eDVBTeletextParser] starting PES reader on pid=ffffffff
< 68400.026323> [eDVBServicePlay] seekTo 0
< 68400.026502> [eDVBChannel] seek.
< 68400.026652> [eFilePushThread] waiting for pause
< 68400.026938> [eFilePushThread] PAUSED
< 68400.027210> [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
< 68400.112563> [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
< 68400.115298> [eDVBChannel] ok, resolved skip (rel: 0, diff 0), now at 00000000
< 68400.340736> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68400.344235> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 68400.345448> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68400.345804> [eDVBSectionReader] DMX_SET_FILTER pid=18
RemovePopup, id = ZapError
[__evUpdatedEventInfo]
[__evUpdatedEventInfo]
resolved to PLAY
< 68400.732801> [eDVBServicePlay] unpause
RemovePopup, id = ZapError
[Skin] SKIN ERROR in MoviePlayer: No module named ServiceEndTime
< 68401.560925> [eDVBChannel] pvrEvent evtStopped
< 68401.561207> [eDVBVideo0] VIDEO_GET_EVENT unhandled DVBAPI Video Event 9
< 68401.561375> [eDVBVideo0] VIDEO_GET_EVENT SIZE_CHANGED 720x576 aspect 2
< 68401.635466> [eDVBVideo0] VIDEO_GET_EVENT FRAME_RATE_CHANGED 25000 fps
< 68401.636084> [eDVBVideo0] VIDEO_GET_EVENT unhandled DVBAPI Video Event 12
< 68402.243692> [eDVBServicePMTHandler] use pmtpid 025d for service_id 1f46
< 68402.244518> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68402.245051> [eDVBSectionReader] DMX_SET_FILTER pid=605
< 68402.246292> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68402.258258> [eDVBSectionReader] DMX_SET_FILTER pid=0
[Dish] tuning failed
< 68402.570412> [DVBCAHandler] no more services
< 68403.075488> [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
< 68403.122663> [eDVBServicePlay] have 1 video stream(s) (096e), and 1 audio stream(s) (0d56), and the pcr pid is 096e, and the text pid is ffffffff
< 68403.124960> [eTSMPEGDecoder] decoder state: play, vpid=096e, apid=0d56
< 68403.125172> [eDVBVideo0] VIDEO_CONTINUE ok
< 68403.126032> [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
< 68403.126834> [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
< 68403.134337> [eDVBAudio0] AUDIO_CONTINUE ok
< 68403.238729> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68403.939779> [eDVBSectionReader] DMX_SET_FILTER pid=605
< 68403.981412> [eDVBFrontend] close frontend 2
action: seekdef:6
< 68404.148113> [eDVBServicePlay] seekRelative 1, 5400000
< 68404.148440> [eDVBChannel] seek.
< 68404.148599> [eFilePushThread] waiting for pause
< 68404.257075> [eFilePushThread] PAUSED
< 68404.257476> [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
< 68404.263711> [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
< 68404.307628> [eDVBChannel] ok, resolved skip (rel: 1, diff 5855871), now at 01e1a418
< 68404.354703> [eDVBChannel] pvrEvent evtStopped
action: seekdef:6
< 68405.432422> [eDVBServicePlay] seekRelative 1, 5400000
< 68405.432962> [eDVBChannel] seek.
< 68405.433111> [eFilePushThread] waiting for pause
< 68405.536929> [eFilePushThread] PAUSED
< 68405.537202> [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
< 68405.559903> [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
< 68405.596139> [eDVBChannel] ok, resolved skip (rel: 1, diff 11291871), now at 035bd8d4
< 68405.718213> [eDVBChannel] pvrEvent evtStopped
action: seekdef:6
< 68406.986326> [eDVBServicePlay] seekRelative 1, 5400000
< 68406.986570> [eDVBChannel] seek.
< 68406.986704> [eFilePushThread] waiting for pause
< 68407.136572> [eFilePushThread] PAUSED
< 68407.137373> [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
< 68407.156096> [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
< 68407.172650> [eDVBChannel] ok, resolved skip (rel: 1, diff 16781871), now at 04fbbf40
< 68407.191920> [eDVBChannel] pvrEvent evtStopped
< 68416.013338> [eDVBVolumecontrol] Setvolume: raw: 95 95, -1db: 4 4
< 68416.013662> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 95
Traceback (most recent call last):
File "/usr/lib/enigma2/python/mytest.py", line 167, in processDelay
callback(*retval)
File "usr/lib/enigma2/python/Screens/InfoBarGenerics.py", line 3185, in aspectSelected
AttributeError: 'MoviePlayer' object has no attribute 'subserviceSelection'
< 68418.655902> [ePyObject] (CallObject(<bound method Session.processDelay of <__main__.Session instance at 0x2c9861cc>>,()) failed)
]=]=>
</enigma2crashlog>
</crashlogs>
</HDMU>
After the enigma2 restart, the same steps did not reproduce the problem - I tried many times and it has not crashed again.
Note: I am booting from an nfs server and /hdd is soft linked to a network drive.
SPARK7162:~# ls -al /hdd
lrwxrwxrwx 1 root root 15 Sep 9 23:04 /hdd -> /media/net/nfs/
<?xml version="1.0" encoding="utf-8"?>
<HDMU>
<enigma2>
<crashdate>Sun Sep 13 12:03:30 2015</crashdate>
<compiledate>Sep 9 2015</compiledate>
<skin>skin.xml</skin>
<sourcedate>Sep 9 2015</sourcedate>
<rev>
<=!=[=C=D=A=T=A=[
-----====== HDMU 14084 enigma2 Git 3909 ======-----
]=]=>
</rev>
<freespace>
<=!=[=C=D=A=T=A=[
Filesystem 1K-blocks Used Available Use% Mounted on
/dev/root 2884283616 1214665120 1523105248 44% /
tmpfs 58596 0 58596 0% /ram
/dev/root 2884283616 1214665120 1523105248 44% /dev.static
tmpfs 58596 0 58596 0% /dev
tmpfs 58596 0 58596 0% /dev/shm
tmpfs 58596 12 58584 0% /tmp
192.168.1.11:/alien2/share/
1465136000 1460161408 4974592 100% /media/net/nfs
]=]=>
</freespace>
<freeram>
<=!=[=C=D=A=T=A=[
MemTotal: 117196 kB
MemFree: 2320 kB
Buffers: 0 kB
Cached: 37760 kB
SwapCached: 0 kB
Active: 44460 kB
Inactive: 55956 kB
Active(anon): 31296 kB
Inactive(anon): 31372 kB
Active(file): 13164 kB
Inactive(file): 24584 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 0 kB
SwapFree: 0 kB
Dirty: 0 kB
Writeback: 0 kB
AnonPages: 62684 kB
Mapped: 11792 kB
Shmem: 12 kB
Slab: 5984 kB
SReclaimable: 1568 kB
SUnreclaim: 4416 kB
KernelStack: 656 kB
PageTables: 372 kB
Quicklists: 196 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 58596 kB
Committed_AS: 140520 kB
VmallocTotal: 507824 kB
VmallocUsed: 5840 kB
VmallocChunk: 473008 kB
]=]=>
</freeram>
<version>4</version>
</enigma2>
<image>
<stbmodel>spark7162</stbmodel>
<kernelversion>
<=!=[=C=D=A=T=A=[
Linux SPARK7162 2.6.32.61_stm24_0217 #1 PREEMPT Wed Sep 9 19:40:17 CEST 2015 sh4 GNU/Linux
]=]=>
</kernelversion>
<kernelcmdline>console=ttyAS0,115200 rw root=/dev/nfs nfsroot=192.168.1.1:/alien2/hdmu,nfsvers=3,rsize=16384,wsize=65536,udp init=/bin/devinit coprocessor_mem=4m@0x40000000,4m@0x40400000 printk=1 ip=192.168.1.11:192.168.1.1:192.168.1.1:255.255.25 5.0:SPARK7162:eth0:off stmmaceth=msglvl:0,phyaddr:2,watchdog:5000 loglevel=6 nfsrootdebug nwhwconf=device:eth0,hwaddr:30:00:0A:00:30:60 Manufacture=Spark-Project STB_ID=0C:00:0A:00:00:30:60</kernelcmdline>
<nimsockets>NIM Socket 0:</nimsockets>
<imageversion>
<=!=[=C=D=A=T=A=[
version=0350200905300000
]=]=>
</imageversion>
<imageissue>
<!-- No such file or directory -->
</imageissue>
</image>
<crashlogs>
<enigma2crashlog>
<=!=[=C=D=A=T=A=[
.089985> [eDVBLocalTimerHandler] time update to 09:38:27
< 59716.090126> [eDVBLocalTimerHandler] m_time_difference is -1
< 59716.090263> [eDVBLocalTimerHandler] set Linux Time
< 59716.091331> [eDVBChannel] getDemux cap=00
[__evUpdatedEventInfo]
< 60709.895471> [eDVBServicePlay] timeshift
< 60709.896299> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 60709.930012> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 60709.930678> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 61516.106509> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 61516.107081> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 61531.106579> [eDVBLocalTimerHandler] diff is -1
< 61531.106790> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 61531.107268> [eDVBLocalTimerHandler] no RTC available :(
< 61531.107470> [eDVBLocalTimerHandler] time update to 10:08:42
< 61531.107574> [eDVBLocalTimerHandler] m_time_difference is -1
< 61531.107662> [eDVBLocalTimerHandler] set Linux Time
< 61531.115596> [eDVBChannel] getDemux cap=00
< 61992.519137> [eEPGCache] start caching events(1442132184)
< 61992.519418> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 61992.520154> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 61992.520882> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 62004.153365> [eEPGCache] nownext finished(1442132196)
< 62022.532947> [eEPGCache] schedule finished(1442132214)
< 62070.573124> [convertDVBUTF8] reserved 24
< 62089.541649> [eEPGCache] schedule other finished(1442132281)
< 62089.542753> [eEPGCache] stop caching events(1442132281)
< 62089.542936> [eEPGCache] next update in 60 min
[__evUpdatedEventInfo]
< 62509.956389> [eDVBServicePlay] timeshift
< 62509.957186> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 62510.000964> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 62510.001415> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 63331.128636> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 63331.129044> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 63346.112730> [eDVBLocalTimerHandler] diff is -1
< 63346.113169> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 63346.113722> [eDVBLocalTimerHandler] no RTC available :(
< 63346.113899> [eDVBLocalTimerHandler] time update to 10:38:57
< 63346.113997> [eDVBLocalTimerHandler] m_time_difference is -1
< 63346.114085> [eDVBLocalTimerHandler] set Linux Time
< 63346.115049> [eDVBChannel] getDemux cap=00
< 65146.130991> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 65146.131336> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 65161.127213> [eDVBLocalTimerHandler] diff is -1
< 65161.127488> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 65161.128149> [eDVBLocalTimerHandler] no RTC available :(
< 65161.128378> [eDVBLocalTimerHandler] time update to 11:09:12
< 65161.128502> [eDVBLocalTimerHandler] m_time_difference is -1
< 65161.128610> [eDVBLocalTimerHandler] set Linux Time
< 65161.129677> [eDVBChannel] getDemux cap=00
< 65689.644097> [eEPGCache] start caching events(1442135881)
< 65689.644378> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 65689.645112> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 65689.645844> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 65703.638137> [eEPGCache] nownext finished(1442135895)
< 65719.753688> [eEPGCache] schedule finished(1442135911)
< 65786.677790> [eEPGCache] schedule other finished(1442135978)
< 65786.678651> [eEPGCache] stop caching events(1442135978)
< 65786.678805> [eEPGCache] next update in 60 min
< 66961.145076> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 66961.145429> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 66976.129139> [eDVBLocalTimerHandler] diff is -1
< 66976.129349> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 66976.129837> [eDVBLocalTimerHandler] no RTC available :(
< 66976.130039> [eDVBLocalTimerHandler] time update to 11:39:27
< 66976.130140> [eDVBLocalTimerHandler] m_time_difference is -1
< 66976.130228> [eDVBLocalTimerHandler] set Linux Time
< 66976.131166> [eDVBChannel] getDemux cap=00
[__evUpdatedEventInfo]
< 67310.215686> [eDVBServicePlay] timeshift
< 67310.217894> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 67310.252853> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 67310.253179> [eDVBSectionReader] DMX_SET_FILTER pid=18
[__evUpdatedEventInfo]
< 68210.231927> [eDVBServicePlay] timeshift
< 68210.232733> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 68210.272042> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 68210.272371> [eDVBSectionReader] DMX_SET_FILTER pid=18
[__evUpdatedEventInfo]
< 68235.325830> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 68235.326155> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 68358.250287> [eDVBServicePlay] timeshift
< 68358.254385> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 68362.607795> [eDVBVolumecontrol] Setvolume: raw: 50 50, -1db: 32 32
< 68362.608116> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 50
< 68363.379851> [eDVBVolumecontrol] Setvolume: raw: 55 55, -1db: 29 29
< 68363.380171> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 55
< 68363.727203> [eDVBVolumecontrol] Setvolume: raw: 60 60, -1db: 26 26
< 68363.727522> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 60
< 68364.036179> [eDVBVolumecontrol] Setvolume: raw: 65 65, -1db: 23 23
< 68364.036512> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 65
< 68364.327810> [eDVBVolumecontrol] Setvolume: raw: 70 70, -1db: 19 19
< 68364.328139> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 70
< 68364.516007> [eDVBVolumecontrol] Setvolume: raw: 75 75, -1db: 16 16
< 68364.516585> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 75
< 68364.848112> [eDVBVolumecontrol] Setvolume: raw: 80 80, -1db: 13 13
< 68364.848445> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 80
< 68365.087986> [eDVBVolumecontrol] Setvolume: raw: 85 85, -1db: 10 10
< 68365.088313> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 85
< 68365.332026> [eDVBVolumecontrol] Setvolume: raw: 90 90, -1db: 7 7
< 68365.332615> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 90
< 68367.144304> [eDVBServicePlay] timeshift
< 68367.145941> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 68382.799465> [eDVBServicePlay] timeshift
< 68382.800554> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
< 68382.822048> [eDVBServicePlay] timeshift
< 68382.822905> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
warning, skin is missing element chosenletter in <class 'Screens.MovieSelection.MovieSelection'>
warning, skin is missing element movie_sort in <class 'Screens.MovieSelection.MovieSelection'>
[Skin] Attribute not implemented: RecordingColor value: #00f23d21
[Skin] Attribute not implemented: FinishedColor value: #0065C416
[Skin] Attribute not implemented: WatchingColor value: #00bab329
[Skin] Attribute not implemented: UnwatchedColor value: #00ffffff
warning, skin is missing element movie_off in <class 'Screens.MovieSelection.MovieSelection'>
HOTPLUG connection!
< 68388.147092> [gRC] main thread is non-idle! display spinner!
setPixmapNum(1) failed! defined pixmaps: []
setPixmapNum(7) failed! defined pixmaps: []
hotplug: ACTION=add
complete ACTION=add
HOTPLUG connection lost!
hotplug: {'ACTION': 'add', 'PHYSDEVPATH': '-', 'DEVPATH': '/devices/virtual/bdi/0:17'}
couldn't read model: [Errno 2] No such file or directory: '/sys/block/0:1/device/model'
< 68390.263643> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file.ts
< 68390.314073> [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 90052, 6891229114 (76569s), fixup to: 90052, 24344 (0s)
< 68390.356566> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file2.ts
< 68390.396955> [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 27448, 592386653 (6582s), fixup to: 27448, 13071 (0s)
< 68390.495435> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file3.ts
< 68390.497664> [eDVBTSTools] getPTS read error
< 68390.497867> [eDVBTSTools] calcEnd [@ML] m_streaminfo.getLastFrame failed, fallback
< 68390.629893> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file4.ts
< 68390.630507> [eDVBTSTools] getPTS read error
< 68390.630685> [eDVBTSTools] calcEnd [@ML] m_streaminfo.getLastFrame failed, fallback
< 68398.114196> [eDVBServicePlay] timeshift
< 68398.116640> [eDVBServicePlay] timeshift not enough diskspace for timeshift! (less than 200MB)
[SKIN] Parsing embedded skin <embedded-in-'Screensaver'>
not pauseable.
RemovePopup, id = ZapError
playing 1:0:0:0:0:0:0:0:0:0:/hdd/movie/file2.ts
< 68399.224656> [eDVBCAService] free slot 1 demux 0 for service 1:0:1:1D5F:C3B4:7E:460000:0:0:0:
< 68399.229737> [eDVBCAService] free service 1:0:1:1D5F:C3B4:7E:460000:0:0:0:
< 68399.415743> [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
< 68399.416002> [eDVBPCR0] DEMUX_STOP ok
< 68399.416413> [eDVBPCR0] destroy
< 68399.416668> [eDVBVideo0] DEMUX_STOP ok
< 68399.417367> [eDVBVideo0] VIDEO_STOP ok
< 68399.532790> [eDVBVideo0] destroy
< 68399.533266> [eDVBAudio0] AUDIO_STOP ok
< 68399.703452> [eDVBAudio0] DEMUX_STOP ok
< 68399.704303> [eDVBAudio0] AUDIO_CONTINUE ok
< 68399.704577> [eDVBAudio0] destroy
< 68399.733565> [eDVBLocalTimerHandler] remove channel 0x2d962220
< 68399.734420> [eEPGCache] remove channel 0x2d962220
getResolvedKey config.usage.remote_fallback failed !! (Typo??)
< 68399.759368> [eDVBServicePMTHandler] alloc PVR
< 68399.759916> [eDVBChannel] getDemux cap=01
< 68399.760228> [eDVBResourceManager] allocate demux cap=01
< 68399.760394> [eDVBResourceManager] allocating demux adapter=0, demux=3, source=-1 fesource=-1
< 68399.760578> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68399.760835> [eDVBTSTools] setSource loading streaminfo for /hdd/movie/file2.ts
< 68399.830495> [eDVBServicePlay] cuts file has 2 entries
< 68399.830849> [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 27448, 592386653 (6582s), fixup to: 27448, 13071 (0s)
< 68399.843824> [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
< 68399.844055> [eDVBServicePlay] have 1 video stream(s) (096e), and 1 audio stream(s) (0d56), and the pcr pid is 096e, and the text pid is ffffffff
< 68399.847204> [setIoPrio] best-effort level 0 ok
< 68399.855758> [eFilePushThread] START thread
< 68399.877114> [eTSMPEGDecoder] decoder state: play, vpid=096e, apid=0d56
ok
< 68399.877789> [eDVBAudio0] AUDIO_SET_BYPASS bypass=1 ok
< 68399.877968> [eDVBAudio0] AUDIO_PLAY ok
< 68399.911592> [eDVBVideo] Video Device: /dev/dvb/adapter0/video0
< 68399.912472> [eDVBVideo] demux device: /dev/dvb/adapter0/demux3
< 68399.912840> [eDVBVideo0] VIDEO_SET_STREAMTYPE 0 - ok
< 68399.913187> [eDVBVideo0] DMX_SET_PES_FILTER pid=0x096e ok
< 68399.914116> [eDVBVideo0] VIDEO_PLAY ok
< 68400.015604> [eDVBVideo0] VIDEO_CONTINUE ok
< 68400.016107> [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
< 68400.016567> [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
< 68400.017042> [eDVBAudio0] AUDIO_CONTINUE ok
< 68400.017722> [eDVBTeletextParser] Starting!
< 68400.017885> [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
< 68400.018080> [eDVBPESReader] Created. Opening demux
< 68400.018217> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68400.024943> [eDVBTeletextParser] created teletext subtitle PES reader!
< 68400.025277> [eDVBPESReader] Created. Opening demux
< 68400.025418> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68400.025905> [eDVBTeletextParser] starting PES reader on pid=ffffffff
< 68400.026323> [eDVBServicePlay] seekTo 0
< 68400.026502> [eDVBChannel] seek.
< 68400.026652> [eFilePushThread] waiting for pause
< 68400.026938> [eFilePushThread] PAUSED
< 68400.027210> [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
< 68400.112563> [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
< 68400.115298> [eDVBChannel] ok, resolved skip (rel: 0, diff 0), now at 00000000
< 68400.340736> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68400.344235> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 68400.345448> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68400.345804> [eDVBSectionReader] DMX_SET_FILTER pid=18
RemovePopup, id = ZapError
[__evUpdatedEventInfo]
[__evUpdatedEventInfo]
resolved to PLAY
< 68400.732801> [eDVBServicePlay] unpause
RemovePopup, id = ZapError
[Skin] SKIN ERROR in MoviePlayer: No module named ServiceEndTime
< 68401.560925> [eDVBChannel] pvrEvent evtStopped
< 68401.561207> [eDVBVideo0] VIDEO_GET_EVENT unhandled DVBAPI Video Event 9
< 68401.561375> [eDVBVideo0] VIDEO_GET_EVENT SIZE_CHANGED 720x576 aspect 2
< 68401.635466> [eDVBVideo0] VIDEO_GET_EVENT FRAME_RATE_CHANGED 25000 fps
< 68401.636084> [eDVBVideo0] VIDEO_GET_EVENT unhandled DVBAPI Video Event 12
< 68402.243692> [eDVBServicePMTHandler] use pmtpid 025d for service_id 1f46
< 68402.244518> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68402.245051> [eDVBSectionReader] DMX_SET_FILTER pid=605
< 68402.246292> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68402.258258> [eDVBSectionReader] DMX_SET_FILTER pid=0
[Dish] tuning failed
< 68402.570412> [DVBCAHandler] no more services
< 68403.075488> [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
< 68403.122663> [eDVBServicePlay] have 1 video stream(s) (096e), and 1 audio stream(s) (0d56), and the pcr pid is 096e, and the text pid is ffffffff
< 68403.124960> [eTSMPEGDecoder] decoder state: play, vpid=096e, apid=0d56
< 68403.125172> [eDVBVideo0] VIDEO_CONTINUE ok
< 68403.126032> [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
< 68403.126834> [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
< 68403.134337> [eDVBAudio0] AUDIO_CONTINUE ok
< 68403.238729> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
< 68403.939779> [eDVBSectionReader] DMX_SET_FILTER pid=605
< 68403.981412> [eDVBFrontend] close frontend 2
action: seekdef:6
< 68404.148113> [eDVBServicePlay] seekRelative 1, 5400000
< 68404.148440> [eDVBChannel] seek.
< 68404.148599> [eFilePushThread] waiting for pause
< 68404.257075> [eFilePushThread] PAUSED
< 68404.257476> [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
< 68404.263711> [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
< 68404.307628> [eDVBChannel] ok, resolved skip (rel: 1, diff 5855871), now at 01e1a418
< 68404.354703> [eDVBChannel] pvrEvent evtStopped
action: seekdef:6
< 68405.432422> [eDVBServicePlay] seekRelative 1, 5400000
< 68405.432962> [eDVBChannel] seek.
< 68405.433111> [eFilePushThread] waiting for pause
< 68405.536929> [eFilePushThread] PAUSED
< 68405.537202> [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
< 68405.559903> [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
< 68405.596139> [eDVBChannel] ok, resolved skip (rel: 1, diff 11291871), now at 035bd8d4
< 68405.718213> [eDVBChannel] pvrEvent evtStopped
action: seekdef:6
< 68406.986326> [eDVBServicePlay] seekRelative 1, 5400000
< 68406.986570> [eDVBChannel] seek.
< 68406.986704> [eFilePushThread] waiting for pause
< 68407.136572> [eFilePushThread] PAUSED
< 68407.137373> [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
< 68407.156096> [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
< 68407.172650> [eDVBChannel] ok, resolved skip (rel: 1, diff 16781871), now at 04fbbf40
< 68407.191920> [eDVBChannel] pvrEvent evtStopped
< 68416.013338> [eDVBVolumecontrol] Setvolume: raw: 95 95, -1db: 4 4
< 68416.013662> [eDVBVolumecontrol] SetVolume failed to open mixer: Device or resource busy
setValue 95
Traceback (most recent call last):
File "/usr/lib/enigma2/python/mytest.py", line 167, in processDelay
callback(*retval)
File "usr/lib/enigma2/python/Screens/InfoBarGenerics.py", line 3185, in aspectSelected
AttributeError: 'MoviePlayer' object has no attribute 'subserviceSelection'
< 68418.655902> [ePyObject] (CallObject(<bound method Session.processDelay of <__main__.Session instance at 0x2c9861cc>>,()) failed)
]=]=>
</enigma2crashlog>
</crashlogs>
</HDMU>
After the enigma2 restart, the same steps did not reproduce the problem - I tried many times and it has not crashed again.
Note: I am booting from an nfs server and /hdd is soft linked to a network drive.
SPARK7162:~# ls -al /hdd
lrwxrwxrwx 1 root root 15 Sep 9 23:04 /hdd -> /media/net/nfs/