Hyperion issue: playback slowdown and halting.

vevs · July 4, 2017 at 5:08 PM

Hi everybody! I'm using LE8 (builds from kszaq, afl1 and johngalt) either on s905 (K1 Plus) and s905x (Coowell V5) devices, and i've faced same playback problem only when hyperion enabled: absolutely randomly video becomes glitchy, slows down and finally hangs for a while (live-tv) or jumps-over a little bit forward (other media). Same time in kodi.log appears "Process - stream stalled" and "WaitForBuffer - timeout waiting for buffer" warnings. One more thing i've noticed - during hyperion's running there is enormous amount of "codec:amvideocap"-related messages floods the kernel's log (see example below). Googling this messages gave me this, so now i believe that my problems are related to kernel.

Please, could someone of our amazing developers help me to overcome this annoying bug? Many thanks in advance!

Display Spoiler

...

[35612.339682@0] codec:*****************Into VIDTYPE_VIU_422******************

[35612.339687@0] codec:RGB_phy_addr:67e00000

[35612.339692@0] codec:RGB_addr:ffffffc067e00000

[35612.339703@0] codec:y_index=[0xf1] u_index=[0x0] cur_index:f1

[35612.339709@0] codec:w=3840-height=544 cur_index:f1

[35612.339715@0] codec:cd.addr:67e00000

[35612.342564@0] codec:amvideocap_capture_one_frame: capture ok priv->want.fmt=16777728

[35612.342571@0] codec:amvideocap_capture_one_frame priv->state=300

[35612.342575@0] codec:amvideocap_capture_one_frame_wait ret=0

[35612.342583@0] codec:amvideocap_read=0,priv->state=300,priv->vaddr=ffffffc067e00000

[35612.342588@0] codec:priv->out_width=160 priv->out_height=160

[35612.342593@0] codec: priv->outfmt_byteppix=3, size=76800

[35612.379084@0] codec:start amvideocap_read waitdelay=1000

[35612.379128@0] codec:amvideocap_capture_one_frame:start vf= (null),index=0

[35612.379154@0] codec:amvideocap_capture_one_frame: get vf type=3800

[35612.379178@0] codec:*****************Into VIDTYPE_VIU_422******************

[35612.379198@0] codec:RGB_phy_addr:67e00000

[35612.379215@0] codec:RGB_addr:ffffffc067e00000

[35612.379250@0] codec:y_index=[0xf1] u_index=[0x0] cur_index:f1

[35612.379272@0] codec:w=3840-height=544 cur_index:f1

[35612.379290@0] codec:cd.addr:67e00000

[35612.382335@0] codec:amvideocap_capture_one_frame: capture ok priv->want.fmt=16777728

[35612.382358@0] codec:amvideocap_capture_one_frame priv->state=300

[35612.382376@0] codec:amvideocap_capture_one_frame_wait ret=0

[35612.382402@0] codec:amvideocap_read=0,priv->state=300,priv->vaddr=ffffffc067e00000

[35612.382423@0] codec:priv->out_width=160 priv->out_height=160

[35612.382441@0] codec: priv->outfmt_byteppix=3, size=76800

[35612.419072@3] codec:start amvideocap_read waitdelay=1000

[35612.419114@3] codec:amvideocap_capture_one_frame:start vf= (null),index=0

[35612.419140@3] codec:amvideocap_capture_one_frame: get vf type=3800

[35612.419163@3] codec:*****************Into VIDTYPE_VIU_422******************

[35612.419182@3] codec:RGB_phy_addr:67e00000

[35612.419200@3] codec:RGB_addr:ffffffc067e00000

[35612.419233@3] codec:y_index=[0xf1] u_index=[0x0] cur_index:f1

[35612.419255@3] codec:w=3840-height=544 cur_index:f1

[35612.419276@3] codec:cd.addr:67e00000

[35612.422338@0] codec:amvideocap_capture_one_frame: capture ok priv->want.fmt=16777728

[35612.422364@0] codec:amvideocap_capture_one_frame priv->state=300

[35612.422383@0] codec:amvideocap_capture_one_frame_wait ret=0

[35612.422410@0] codec:amvideocap_read=0,priv->state=300,priv->vaddr=ffffffc067e00000

[35612.422431@0] codec:priv->out_width=160 priv->out_height=160

[35612.422449@0] codec: priv->outfmt_byteppix=3, size=76800

[35612.459681@3] codec:start amvideocap_read waitdelay=1000

[35612.459697@3] codec:amvideocap_capture_one_frame:start vf= (null),index=0

[35612.459705@3] codec:amvideocap_capture_one_frame: get vf type=3800

[35612.459711@3] codec:*****************Into VIDTYPE_VIU_422******************

[35612.459717@3] codec:RGB_phy_addr:67e00000

[35612.459721@3] codec:RGB_addr:ffffffc067e00000

[35612.459731@3] codec:y_index=[0xf1] u_index=[0x0] cur_index:f1

[35612.459737@3] codec:w=3840-height=544 cur_index:f1

[35612.459742@3] codec:cd.addr:67e00000

[35612.462592@3] codec:amvideocap_capture_one_frame: capture ok priv->want.fmt=16777728

[35612.462598@3] codec:amvideocap_capture_one_frame priv->state=300

[35612.462603@3] codec:amvideocap_capture_one_frame_wait ret=0

[35612.462610@3] codec:amvideocap_read=0,priv->state=300,priv->vaddr=ffffffc067e00000

[35612.462616@3] codec:priv->out_width=160 priv->out_height=160

[35612.462621@3] codec: priv->outfmt_byteppix=3, size=76800

[35612.499661@3] codec:start amvideocap_read waitdelay=1000

[35612.499679@3] codec:amvideocap_capture_one_frame:start vf= (null),index=0

[35612.499686@3] codec:amvideocap_capture_one_frame: get vf type=3800

[35612.499693@3] codec:*****************Into VIDTYPE_VIU_422******************

[35612.499699@3] codec:RGB_phy_addr:67e00000

[35612.499703@3] codec:RGB_addr:ffffffc067e00000

[35612.499714@3] codec:y_index=[0xf1] u_index=[0x0] cur_index:f1

[35612.499720@3] codec:w=3840-height=544 cur_index:f1

[35612.499725@3] codec:cd.addr:67e00000

[35612.502589@3] codec:amvideocap_capture_one_frame: capture ok priv->want.fmt=16777728

[35612.502596@3] codec:amvideocap_capture_one_frame priv->state=300

[35612.502601@3] codec:amvideocap_capture_one_frame_wait ret=0

[35612.502608@3] codec:amvideocap_read=0,priv->state=300,priv->vaddr=ffffffc067e00000

[35612.502614@3] codec:priv->out_width=160 priv->out_height=160

[35612.502619@3] codec: priv->outfmt_byteppix=3, size=76800

[35612.540390@3] codec:start amvideocap_read waitdelay=1000

[35612.540407@3] codec:amvideocap_capture_one_frame:start vf= (null),index=0

[35612.540416@3] codec:amvideocap_capture_one_frame: get vf type=3800

[35612.540423@3] codec:*****************Into VIDTYPE_VIU_422******************

[35612.540429@3] codec:RGB_phy_addr:67e00000

[35612.540434@3] codec:RGB_addr:ffffffc067e00000

[35612.540445@3] codec:y_index=[0xf1] u_index=[0x0] cur_index:f1

[35612.540452@3] codec:w=3840-height=544 cur_index:f1

[35612.540457@3] codec:cd.addr:67e00000

[35612.543332@3] codec:amvideocap_capture_one_frame: capture ok priv->want.fmt=16777728

[35612.543344@3] codec:amvideocap_capture_one_frame priv->state=300

[35612.543349@3] codec:amvideocap_capture_one_frame_wait ret=0

[35612.543357@3] codec:amvideocap_read=0,priv->state=300,priv->vaddr=ffffffc067e00000

[35612.543363@3] codec:priv->out_width=160 priv->out_height=160

[35612.543368@3] codec: priv->outfmt_byteppix=3, size=76800

[35612.578968@3] codec:start amvideocap_read waitdelay=1000

[35612.578995@3] codec:amvideocap_capture_one_frame:start vf= (null),index=0

[35612.579009@3] codec:amvideocap_capture_one_frame: get vf type=3800

[35612.579021@3] codec:*****************Into VIDTYPE_VIU_422******************

[35612.579032@3] codec:RGB_phy_addr:67e00000

[35612.579040@3] codec:RGB_addr:ffffffc067e00000

[35612.579060@3] codec:y_index=[0xf1] u_index=[0x0] cur_index:f1

[35612.579072@3] codec:w=3840-height=544 cur_index:f1

[35612.579082@3] codec:cd.addr:67e00000

[35612.582031@3] codec:amvideocap_capture_one_frame: capture ok priv->want.fmt=16777728

[35612.582046@3] codec:amvideocap_capture_one_frame priv->state=300

[35612.582055@3] codec:amvideocap_capture_one_frame_wait ret=0

[35612.582070@3] codec:amvideocap_read=0,priv->state=300,priv->vaddr=ffffffc067e00000

[35612.582080@3] codec:priv->out_width=160 priv->out_height=160

[35612.582090@3] codec: priv->outfmt_byteppix=3, size=76800

[35612.618870@3] codec:start amvideocap_read waitdelay=1000

[35612.618898@3] codec:amvideocap_capture_one_frame:start vf= (null),index=0

[35612.618921@3] codec:amvideocap_capture_one_frame: get vf type=3800

[35612.618933@3] codec:*****************Into VIDTYPE_VIU_422******************

[35612.618944@3] codec:RGB_phy_addr:67e00000

[35612.618952@3] codec:RGB_addr:ffffffc067e00000

[35612.618970@3] codec:y_index=[0xf1] u_index=[0x0] cur_index:f1

[35612.618982@3] codec:w=3840-height=544 cur_index:f1

[35612.618992@3] codec:cd.addr:67e00000

[35612.621962@3] codec:amvideocap_capture_one_frame: capture ok priv->want.fmt=16777728

[35612.621980@3] codec:amvideocap_capture_one_frame priv->state=300

[35612.621990@3] codec:amvideocap_capture_one_frame_wait ret=0

[35612.622004@3] codec:amvideocap_read=0,priv->state=300,priv->vaddr=ffffffc067e00000

[35612.622015@3] codec:priv->out_width=160 priv->out_height=160

[35612.622024@3] codec: priv->outfmt_byteppix=3, size=76800

[35612.659671@3] codec:start amvideocap_read waitdelay=1000

[35612.659687@3] codec:amvideocap_capture_one_frame:start vf= (null),index=0

[35612.659694@3] codec:amvideocap_capture_one_frame: get vf type=3800

[35612.659701@3] codec:*****************Into VIDTYPE_VIU_422******************

...

**kszaq** · July 4, 2017 at 8:29 PM

I will include the amvideocap from Memphiz in my next build. I'm wondering why I forgot it, perhaps nobody need it before.

Remember that amvideocap may not work properly for 4K videos.

vevs · July 5, 2017 at 7:12 PM

Thanks for your efforts, kszaq! But problem still persist with same symptoms

Display Spoiler

20:58:09.207 T:3543135136 NOTICE: CVideoPlayerAudio::Process - stream stalled

20:58:10.189 T:3901387680 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer

20:59:20.191 T:3543135136 NOTICE: CVideoPlayerAudio::Process - stream stalled

20:59:20.910 T:3901387680 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer

21:00:05.496 T:3543135136 NOTICE: CVideoPlayerAudio::Process - stream stalled

Maybe this bug is related to kernel log spam from amvideocap (check the link in my 1st post).

JonSnow · July 7, 2017 at 12:37 AM

Yup same issue, using Hyperion from LE repo.

i think it may have to do with the cache size in advancedsettings.xml, particularly readfactor.

I originally had readfactor set to 5 and buffermode set to 0.

now its the below.

<cache>

</cache>

its taking much longer for the slowdown to occur, before it was 40 minutes now its 1:10.

EDIT: In the kodi logs i see NOTICE: CVideoPlayerAudio::Process - stream stalled, trying again with buffermode set to 1.

Momo90 · July 11, 2017 at 7:25 AM

Hi! could you solve the problem?

sunnyfunny · July 11, 2017 at 9:17 PM

I am in this topic times, because I have the same problem.

vevs · July 13, 2017 at 5:48 PM

Please, maybe someone of the developers could try to build kernel with changes, mentioned in my first post? I'm not experianced enough for build a kernel by myself, but i can do heavy testing There is no need to rebuild a whole LE on every testing iteration - i need just kernel image, so maybe somebody could spend some time for this? Thanks in advance!

**kszaq** · July 13, 2017 at 10:17 PM

The patch is included in my 8.0.2e build.

JonSnow · July 14, 2017 at 3:38 AM

Kszaq been running 8.0.2e since release and tried everything but the video still slows down. I read it was related to having both the internal grabber and AML grabber, removing the internal grabber had no effect.

vevs · July 14, 2017 at 10:20 AM

Quote from kszaq

The patch is included in my 8.0.2e build.

Hi kszaq! Obviously, you missed my report

Quote from vevs

problem still persist with same symptoms

Also, Memphiz amvideocap doesn't solve the kernel log spam issue.

**kszaq** · July 14, 2017 at 11:52 AM

Quote from vevs

Hi kszaq! Obviously, you missed my report

No, I did not. You asked to include kernel patches, I told you they are already included.

vevs · July 14, 2017 at 12:15 PM

Oh, ok, you're right So, as i reported, those patches (Memphiz's implemetation) didn't solve the problem. May i ask you also try to remove klog messages from amvideocap, as it done in provided example from my first post? Without those corrections systemd-journal process consumes even more CPU's time than hyperiond itself. And i really hope that this is a reason of playback issues, bacause i don't have any other clue...

TIA from all ambient lighting lovers

vevs · July 20, 2017 at 11:37 AM

In case someone of the developers wants to reproduce amvideocap issue by himself: you don't really need any additional hardware for this. Just install Hyperion from LE repo (or via HyperCon), put any config file with "/dev/null" as output (example), and just use device as usual - problem will appear, sooner or later.

JonSnow · July 21, 2017 at 3:22 AM

Wondering if this is resolved in LE8.2?

vevs · July 21, 2017 at 10:19 AM

Unfortunately no

Marciano · July 21, 2017 at 11:51 AM

I have the same on my odroid C2.

And on my second odroid C2 i installed raybuntu's rb-leia4.3 and no issues so far with hyperion and live tv and other media.

**kszaq** · July 21, 2017 at 11:59 AM

Marciano What about Krypton builds from Raybuntu? Is issue present there?

Marciano · July 21, 2017 at 12:03 PM

Yes

EDIT:

Tested with latest raybunyu's krypton 15.3 on my odroid C2 and no issues with hyperion after i edited my advanced settings.