[PROBLEM] Kodi hangs randomly while on playback

[guillaumesoucy]

Hello,

Why Kodi (running from RPI3b) hangs randomly while playing back video contents using FTP on local servers? What I can see when this happen is the screen just become grey or multi-colours with often some pixels then, the playback stops. If I'm fast enough I could press stop using the wireless remote control but the most of the time I need to pull out the plug from the wall and re-plugin it back into the wall socket and waiting for the reboot complete.

Theses are the links to the logs (it was too long to be able fit in a single shot.):

https://paste.kodi.tv/ulazaguwec.kodi
https://paste.kodi.tv/ilukevuleb.kodi

I did some testing:

From SD card, there no issues. Played from another machine running Linux Mint with Kodi (Intel based) no issues as well using the same FTP server as source.

This what happen on the screen when it get stuck:

The playback froze:



Then I press "OK" key:


Then I attempt to stop the playback:


When the Kodi GUI froze but SSH logins to the machine still work, the machine seem not freezing, only Kodi GUI.

It also often lead to this kind of patterns displayed through the Pi HDMI output:


Sometime its gray such as right now, but it can also be green, sometime purple.
May I please know how to stop this issue to happening?

I'm using the latest version of Kodi (18.4) and also I did the update to Debian Buster.

Thank-you.

Guillaume

[Nachteule]

Not sure that this is the reason, but it seems, that

1) You get IP address via DHCP and
2) Interface is reconfigured while playing vid
3) FTP connection has been closed due to 2)
4) Kodi starts with throwing error after local buffer is empty, no wonder because ftp connection has been canceled

Code:

2019-11-19 00:38:13.131 T:2724197104   DEBUG: <Event dir=False mask=0x100 maskname=IN_CREATE name=dhclient.wlan0.pid path=/run pathname=/run/dhclient.wlan0.pid wd=2 >
2019-11-19 00:38:13.132 T:2724197104   DEBUG: <Event dir=False mask=0x2 maskname=IN_MODIFY name=dhclient.wlan0.pid path=/run pathname=/run/dhclient.wlan0.pid wd=2 >
2019-11-19 00:38:13.138 T:2633614064   DEBUG: CMMALPool::Configure pool:0x40b77e0 480x360 (480x368) pix:-1 size:128 fmt:OPQV
2019-11-19 00:38:14.452 T:2724197104   DEBUG: Previous line repeats 39 times.
2019-11-19 00:38:14.453 T:2724197104   DEBUG: <Event dir=False mask=0x2 maskname=IN_MODIFY name=utmp path=/run pathname=/run/utmp wd=2 >
2019-11-19 00:38:14.475 T:2633614064   DEBUG: Previous line repeats 1 times.
2019-11-19 00:38:14.475 T:2633614064   DEBUG: CMMALPool::Configure pool:0x40b77e0 480x360 (480x368) pix:-1 size:128 fmt:OPQV
2019-11-19 00:38:34.536 T:2985437632   DEBUG: Previous line repeats 601 times.
2019-11-19 00:38:34.536 T:2985437632    INFO: CheckIdle - Closing session to ftp://192.168.2.3 (easy=0x474c258, multi=0x474a100)
2019-11-19 00:38:34.537 T:2633614064   DEBUG: CMMALPool::Configure pool:0x40b77e0 480x360 (480x368) pix:-1 size:128 fmt:OPQV
2019-11-19 00:38:34.972 T:2707419888   DEBUG: Previous line repeats 13 times.
2019-11-19 00:38:34.973 T:2707419888   DEBUG: Thread JobWorker 2707419888 terminating (autodelete)
2019-11-19 00:38:34.973 T:2865119984   DEBUG: Thread JobWorker 2865119984 terminating (autodelete)
2019-11-19 00:38:34.973 T:2715808496   DEBUG: Thread JobWorker 2715808496 terminating (autodelete)
2019-11-19 00:38:34.973 T:2698605296   DEBUG: Thread JobWorker 2698605296 terminating (autodelete)
2019-11-19 00:38:35.004 T:2633614064   DEBUG: CMMALPool::Configure pool:0x40b77e0 480x360 (480x368) pix:-1 size:128 fmt:OPQV
2019-11-19 00:40:16.845 T:2616836848   DEBUG: Previous line repeats 3052 times.
2019-11-19 00:40:16.845 T:2616836848   ERROR: ffmpeg[9BF9C2F0]: [aac] Number of bands (44) exceeds limit (41).
2019-11-19 00:40:16.845 T:2616836848   ERROR: ffmpeg[9BF9C2F0]: [aac] channel element 3.6 is not allocated

So, first I would try to configure static network address and try again

[guillaumesoucy]

(17th Dec, 2019 11:27 PM)Nachteule Wrote:  Not sure that this is the reason, but it seems, that

1) You get IP address via DHCP and
2) Interface is reconfigured while playing vid
3) FTP connection has been closed due to 2)
4) Kodi starts with throwing error after local buffer is empty, no wonder because ftp connection has been canceled

Code:

2019-11-19 00:38:13.131 T:2724197104   DEBUG: <Event dir=False mask=0x100 maskname=IN_CREATE name=dhclient.wlan0.pid path=/run pathname=/run/dhclient.wlan0.pid wd=2 >
2019-11-19 00:38:13.132 T:2724197104   DEBUG: <Event dir=False mask=0x2 maskname=IN_MODIFY name=dhclient.wlan0.pid path=/run pathname=/run/dhclient.wlan0.pid wd=2 >
2019-11-19 00:38:13.138 T:2633614064   DEBUG: CMMALPool::Configure pool:0x40b77e0 480x360 (480x368) pix:-1 size:128 fmt:OPQV
2019-11-19 00:38:14.452 T:2724197104   DEBUG: Previous line repeats 39 times.
2019-11-19 00:38:14.453 T:2724197104   DEBUG: <Event dir=False mask=0x2 maskname=IN_MODIFY name=utmp path=/run pathname=/run/utmp wd=2 >
2019-11-19 00:38:14.475 T:2633614064   DEBUG: Previous line repeats 1 times.
2019-11-19 00:38:14.475 T:2633614064   DEBUG: CMMALPool::Configure pool:0x40b77e0 480x360 (480x368) pix:-1 size:128 fmt:OPQV
2019-11-19 00:38:34.536 T:2985437632   DEBUG: Previous line repeats 601 times.
2019-11-19 00:38:34.536 T:2985437632    INFO: CheckIdle - Closing session to ftp://192.168.2.3 (easy=0x474c258, multi=0x474a100)
2019-11-19 00:38:34.537 T:2633614064   DEBUG: CMMALPool::Configure pool:0x40b77e0 480x360 (480x368) pix:-1 size:128 fmt:OPQV
2019-11-19 00:38:34.972 T:2707419888   DEBUG: Previous line repeats 13 times.
2019-11-19 00:38:34.973 T:2707419888   DEBUG: Thread JobWorker 2707419888 terminating (autodelete)
2019-11-19 00:38:34.973 T:2865119984   DEBUG: Thread JobWorker 2865119984 terminating (autodelete)
2019-11-19 00:38:34.973 T:2715808496   DEBUG: Thread JobWorker 2715808496 terminating (autodelete)
2019-11-19 00:38:34.973 T:2698605296   DEBUG: Thread JobWorker 2698605296 terminating (autodelete)
2019-11-19 00:38:35.004 T:2633614064   DEBUG: CMMALPool::Configure pool:0x40b77e0 480x360 (480x368) pix:-1 size:128 fmt:OPQV
2019-11-19 00:40:16.845 T:2616836848   DEBUG: Previous line repeats 3052 times.
2019-11-19 00:40:16.845 T:2616836848   ERROR: ffmpeg[9BF9C2F0]: [aac] Number of bands (44) exceeds limit (41).
2019-11-19 00:40:16.845 T:2616836848   ERROR: ffmpeg[9BF9C2F0]: [aac] channel element 3.6 is not allocated

So, first I would try to configure static network address and try again

The interface than I'd use is eth0 and it have an static IP. But I agree if the IP addressing was dynamically attributed, it could be the issue in that case.

[guillaumesoucy]

Good morn,

It just fail again but this time from another Raspberry PI 3b+ installation (just set in service now), is adding an "keep alive setting" from advancedsettings.xml config file could help?

Guillaume

[Nachteule]

(28th Jan, 2020 08:42 PM)guillaumesoucy Wrote:  Good morn,

It just fail again but this time from another Raspberry PI 3b+ installation (just set in service now), is adding an "keep alive setting" from advancedsettings.xml config file could help?

Guillaume

Never heard anything about "keep alive" setting in advancedsettings.xml file, sorry.

But I think your problem is based in your network topology and not inside Kodi.

[guillaumesoucy]

I've just allow external access through FTP for testing purposes and it fail even from the outside. According to the 2nd post, it do look happening when the local buffer get empty. So, if we start back from there, what things I can changes in the Kodi (Xbian) settings to get it works right? If there as networking issue, nothing would work in the place, everything works just fine here except the two Xbian RaspberryPi installs.

Regards,

Guillaume

[celinedion]

The issue appears to be caused by a virus that is installed on the local server. The virus is believed to be causing the issue, and it is recommended that you remove it before continuing to use the server. mapquest directions