PDA

View Full Version : [GI-XPEED LX3] Playback cuts in and out (ERROR: accelAlloc failed)


rassi
07-12-14, 18:46
Installed OpenViX from scratch and playback cuts in and out, it just goes "pause" need to wait awhile and it continues and
then again and again and again now and then. Using NFS and OpenViX is Apollo 97 without any extra plugins (Oscam is installed from feeds).
Channel list is created from scratch as well, maintained with newest DreamBoxEdit 6.0.1.1. Box havenīt totally jammed yet, but not tested playback while recording. Old issue can be found from here (http://www.world-of-satellite.com/showthread.php?41718-Favourites-list-spinning-ViX-when-recording-in-progress-after-upgrading-Apollo&highlight=lx3*) Iīm not using Alternative services at the moment. Box do have 3x S2 and 1xC tuners, all S2 tuners are in equal diseqc setup.

Here is piece from Debug log, it seem that everytime when playback gets stuck logs says "ERROR: accelAlloc failed", what causes it?



loading streaminfo for /media/autofs/NASSI/movie/20141207 1713 - Yle Fem - Strömsö.ts
cuts file has 3 entries
[@ML] m_streaminfo.getLastFrame returned 42112, 0 (0s), fixup to: 42112, 10346 (0s)
setIoPrio best-effort level 0 ok
FILEPUSH THREAD START
ok ... now we start!!
eventNewProgramInfo 0 0
have 1 video stream(s) (05ee), and 1 audio stream(s) (0cc5), and the pcr pid is 05ee, and the text pid is 1791
decoder state: play, vpid=1518, apid=3269
DMX_SET_PES_FILTER(0xcc5) - 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/demux4
VIDEO_SET_STREAMTYPE 1 - ok
DMX_SET_PES_FILTER(0x5ee) - video - ok
DEMUX_START - video - ok
VIDEO_FREEZE - ok
VIDEO_PLAY - ok
DMX_SET_PES_FILTER(0x1791) - ttx - ok
DEMUX_START - ttx - ok
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
disable teletext subtitles page ffffffffffffffff (und)
eDVBServicePlay::seekTo: jump 0
seek.
FILEPUSH waiting for pause
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 0, diff 0), now at 00000000
start dvb subtitles on pid 0x19ea with composition_page_id 1 and ancillary_page_id 2
RemovePopup, id = ZapError
eDVBServicePlay::unpause
RemovePopup, id = ZapError
warning, skin is missing element state in <class 'Screens.InfoBar.MoviePlayer'>
warning, skin is missing element eventname in <class 'Screens.InfoBar.MoviePlayer'>
KEY: 352 OK
eDVBChannel: pvrEvent evtStopped
VIDEO_GET_EVENT - ok
VIDEO_GET_EVENT - ok
ERROR reading PES (fd=48) - Value too large for defined data type
poll: unhandled POLLERR/HUP/NVAL for fd 48(8)
accel alloc failed

ERROR: accelAlloc failed
TuxTxt stopped service 1791
cleaning up
TuxTxt cache cleared
TuxTxt cache cleared
cleaning up
TuxTxt cache cleared
TuxTxt cache cleared
TuxTxt cache cleared
TuxTxt: initialized
TuxTxt service started 1791
TuxTxt running thread...(1791)
accel alloc failed

ERROR: accelAlloc failed
PATready
use pmtpid 0061 for service_id 1ba4
[Dish] tuning failed
eventNewProgramInfo 0 0
have 1 video stream(s) (05ee), and 1 audio stream(s) (0cc5), and the pcr pid is 05ee, and the text pid is 1791
decoder state: play, vpid=1518, apid=3269
disable dvb subtitles
disable teletext subtitles page ffffffffffffffff (und)
start dvb subtitles on pid 0x19ea with composition_page_id 1 and ancillary_page_id 2
[VideoMode] setting aspect: 16:9
[VideoMode] setting wss: auto
[VideoMode] setting policy: bestfit
[VideoMode] setting policy2: scale
KEY: 352 OK
action -> MsgBoxActions ok
KEY: 352 OK
[DVBCAHandler] no more services
close frontend 0
KEY: 10 9
eDVBServicePlay::seekRelative: jump 1, 27000000
seek.
FILEPUSH waiting for pause
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 1, diff 29639856), now at 077fc61c
eDVBChannel: pvrEvent evtStopped
Subtitle without PTS and recording
Subtitle without PTS and recording
accel alloc failed

ERROR: accelAlloc failed
setPage
setPage
accel alloc failed

ERROR: accelAlloc failed
accel alloc failed

ERROR: accelAlloc failed
accel alloc failed

ERROR: accelAlloc failed
KEY: 10 9
KEY: 10 9
eDVBServicePlay::seekRelative: jump 1, 27000000
seek.
FILEPUSH waiting for pause
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 1, diff 58979656), now at 0ef6e78c
eDVBChannel: pvrEvent evtStopped
accel alloc failed

ERROR: accelAlloc failed
KEY: 10 9


Whole log attached. Includes many spots with "ERROR: accelAlloc failed"
39032

PS. OpenATV do not have this kind of playback issues at all.
rassi
09-12-14, 18:00
AccelAlloc error probably not the thing.

Itīs very annoying to watch recordings, itīll do it too often.
It could be this point in the logs when it pauses:


FILEPUSH waiting for pause
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 1, diff 29639856), now at 077fc61c
eDVBChannel: pvrEvent evtStopped
rassi
14-12-14, 13:01
Issue continues with newest build.



loading streaminfo for /media/autofs/NASSI/movie/20141212 2005 - MTV3 HD - Posse.ts
cuts file has 3 entries
[@ML] m_streaminfo.getLastFrame returned 119192, 0 (0s), fixup to: 119192, 10452 (0s)
setIoPrio best-effort level 0 ok
FILEPUSH THREAD START
ok ... now we start!!
eventNewProgramInfo 0 0
have 1 video stream(s) (05b4), and 1 audio stream(s) (0de8), and the pcr pid is 05b4, and the text pid is 1933
decoder state: play, vpid=1460, apid=3560
DMX_SET_PES_FILTER(0xde8) - 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/demux4
VIDEO_SET_STREAMTYPE 1 - ok
DMX_SET_PES_FILTER(0x5b4) - video - ok
DEMUX_START - video - ok
VIDEO_FREEZE - ok
VIDEO_PLAY - ok
DMX_SET_PES_FILTER(0x1933) - ttx - ok
DEMUX_START - ttx - ok
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
disable teletext subtitles page ffffffffffffffff (und)
eDVBServicePlay::seekTo: jump 0
seek.
FILEPUSH waiting for pause
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 0, diff 0), now at 00000000
start dvb subtitles on pid 0x1937 with composition_page_id 1 and ancillary_page_id 2
RemovePopup, id = ZapError
eDVBServicePlay::unpause
RemovePopup, id = ZapError
warning, skin is missing element state in <class 'Screens.InfoBar.MoviePlayer'>
warning, skin is missing element eventname in <class 'Screens.InfoBar.MoviePlayer'>
KEY: 352 OK
eDVBChannel: pvrEvent evtStopped
VIDEO_GET_EVENT - ok
VIDEO_GET_EVENT - ok
ERROR reading PES (fd=48) - Value too large for defined data type
poll: unhandled POLLERR/HUP/NVAL for fd 48(8)
PATready
use pmtpid 0230 for service_id 0b76
[Dish] tuning failed
eventNewProgramInfo 0 0
dvb subtitle PID 1937 with wrong subtitling type (14)... force 0x10!!
have 1 video stream(s) (05b4), and 1 audio stream(s) (0de8), and the pcr pid is 05b4, and the text pid is 1933
decoder state: play, vpid=1460, apid=3560
dvb subtitle PID 1937 with wrong subtitling type (14)... force 0x10!!
disable dvb subtitles
disable teletext subtitles page ffffffffffffffff (und)
start dvb subtitles on pid 0x1937 with composition_page_id 1 and ancillary_page_id 2
[VideoMode] setting aspect: 16:9
[VideoMode] setting wss: auto
[VideoMode] setting policy: bestfit
[VideoMode] setting policy2: scale
[DVBCAHandler] no more services
close frontend 0
KEY: 108 DOWN
action -> DirectionActions down
KEY: 108 DOWN
KEY: 352 OK
action -> MsgBoxActions ok
eDVBServicePlay::seekTo: jump 22250486
seek.
FILEPUSH waiting for pause
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 0, diff 22250486), now at 108ed69c
eDVBChannel: pvrEvent evtStopped
KEY: 352 OK
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
[AutoTimer] Auto Poll
[AutoTimer] Auto Poll Started
[EPGC] event 5d8b not found in epgcache
[EPGC] event 63e2 not found in epgcache
[EPGC] event 701e not found in epgcache
[EPGC] event 5526 not found in epgcache
[EPGC] event 4276 not found in epgcache
[EPGC] event 5f43 not found in epgcache
[EPGC] event 113b not found in epgcache
[EPGC] event 5a2d not found in epgcache
[EPGC] event 5913 not found in epgcache
[EPGC] event 5886 not found in epgcache
[EPGC] event 5e16 not found in epgcache
[EPGC] event 50c7 not found in epgcache
[EPGC] event 4b43 not found in epgcache
[EPGC] event 5851 not found in epgcache
[EPGC] event 14fa not found in epgcache
[EPGC] event 3abb not found in epgcache
[EPGC] event 117f not found in epgcache
[EPGC] event 5f26 not found in epgcache
[EPGC] event 1b14 not found in epgcache
[EPGC] event 510a not found in epgcache
[EPGC] event 6570 not found in epgcache
[EPGC] event 63a0 not found in epgcache
[EPGC] event 57b6 not found in epgcache
[EPGC] event 279a not found in epgcache
[EPGC] event 5897 not found in epgcache
[EPGC] event 4fd2 not found in epgcache
[EPGC] event 126c not found in epgcache
[EPGC] event a07c not found in epgcache
[EPGC] event 6142 not found in epgcache
[EPGC] event 5e28 not found in epgcache
[EPGC] event 6c19 not found in epgcache
[EPGC] event 1f38 not found in epgcache
job Components.Task.Job name=Automaattiajastus #tasks=0 completed with [] in None
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
poll: unhandled POLLERR/HUP/NVAL for fd 56(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=Softcam-tarkistus #tasks=1 completed with [] in None
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
poll: unhandled POLLERR/HUP/NVAL for fd 56(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=Softcam-tarkistus #tasks=1 completed with [] in None
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 4 3
eDVBServicePlay::seekRelative: jump 1, 1350000
seek.
FILEPUSH waiting for pause
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 1, diff 49346394), now at 24bb1e44
eDVBChannel: pvrEvent evtStopped
KEY: 4 3
KEY: 4 3
eDVBServicePlay::seekRelative: jump 1, 1350000
seek.
FILEPUSH waiting for pause
main thread is non-idle! display spinner!
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 1, diff 51100393), now at 2609e0e4
KEY: 4 3
KEY: 4 3
eDVBServicePlay::seekRelative: jump 1, 1350000
seek.
FILEPUSH waiting for pause
FILEPUSH THREAD PAUSED
AUDIO_CLEAR_BUFFER - ok
VIDEO_CLEAR_BUFFER - ok
ok, resolved skip (rel: 1, diff 52299727), now at 26ef2a04
KEY: 4 3
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::pause
decoder state: pause, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_FREEZE - ok
AUDIO_PAUSE - ok
KEY: 164 PAUSE
KEY: 164 PAUSE
action -> InfobarSeekActions playpauseService
eDVBServicePlay::unpause
decoder state: play, vpid=1460, apid=3560
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
KEY: 164 PAUSE
[eDVBLocalTimerHandler] no transponder tuned... or no TDT/TOT avail .. try to use RTC :)
[eDVBLocalTimerHandler] RTC time is 12:16:46
[eDVBLocalTimerHandler] Receiver time is 12:16:46
[eDVBLocalTimerHandler] RTC to Receiver time difference is 0 seconds
[eDVBLocalTimerHandler] no change needed
eDVBChannel: pvrEvent evtStopped
ERROR reading PES (fd=48) - Value too large for defined data type
poll: unhandled POLLERR/HUP/NVAL for fd 48(8)
eDVBChannel: pvrEvent evtStopped
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
poll: unhandled POLLERR/HUP/NVAL for fd 56(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=Softcam-tarkistus #tasks=1 completed with [] in None