[RPI4] frame drop/skip

blackhades00 · December 26, 2023 at 1:07 PM

hello,

i'm using a RPI4 with 4GB ram and latest build of libreelec (11.0.4), it's connected via gbit lan and 300mbit fiber connection. temperature while playback is around 50 degree for CPU/GPU, memory load is like 300-400MB / 4GB. so it shouldn't be temperature related or too less system ressources...?

when playing online streams (720p, h264) theres strange behaviour, every few minutes theres a jump/skip for video/audio for like 2-3s. sometimes theres also a green frame visible for like 1-2 frames (at least very brief moment).

downloading the stream and playing it from local storage just works fine without the issues above so it's maybe connection/hoster related?

however when i use different device (tablet/desktop/laptop) running kodi and same stream it works flawless.

i also swapped out lan cable, hdmi cable, even the RPI4 but nothing worked. i also tryed osmc instead of libreelec and same issues there. also it seems to get worse the longer the RPI4 is running, when you just booted up it takes longer for this issue to occur (at least it seems so, testing possibilities are very limited sadly)

to get some information i enabled extended debug log and filtered out all error lines:

Code

Zeile  115: 2023-12-26 08:48:40.330 T:1003    error <general>: DBus error: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
	Zeile  174: 2023-12-26 08:48:40.992 T:1003     info <general>: EGL_EXTENSIONS = EGL_ANDROID_blob_cache EGL_EXT_buffer_age EGL_EXT_image_dma_buf_import EGL_EXT_image_dma_buf_import_modifiers EGL_KHR_cl_event2 EGL_KHR_config_attribs EGL_KHR_context_flush_control EGL_KHR_create_context EGL_KHR_create_context_no_error EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_colorspace EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_EXT_pixel_format_float EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_image_dma_buf_export EGL_MESA_query_driver 
	Zeile  182: 2023-12-26 08:48:41.033 T:1003     info <general>: GL_EXTENSIONS = GL_EXT_blend_minmax GL_EXT_multi_draw_arrays GL_EXT_texture_filter_anisotropic GL_EXT_texture_compression_s3tc GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_format_BGRA8888 GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth24 GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_mapbuffer GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_stencil8 GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_half_float GL_EXT_draw_instanced GL_EXT_texture_sRGB_decode GL_OES_EGL_image GL_OES_depth_texture GL_AMD_performance_monitor GL_OES_packed_depth_stencil GL_EXT_texture_type_2_10_10_10_REV GL_OES_get_program_binary GL_APPLE_texture_max_level GL_EXT_discard_framebuffer GL_EXT_read_format_bgra GL_NV_pack_subimage GL_EXT_frag_depth GL_NV_fbo_color_attachments GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_vertex_array_object GL_ANGLE_pack_reverse_row_order GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_EXT_occlusion_query_boolean GL_EXT_texture_rg GL_EXT_unpack_subimage GL_NV_draw_buffers GL_NV_read_buffer GL_NV_read_depth GL_NV_read_depth_stencil GL_NV_read_stencil GL_EXT_draw_buffers GL_EXT_map_buffer_range GL_KHR_debug GL_KHR_texture_compression_astc_ldr GL_NV_pixel_buffer_object GL_OES_depth_texture_cube_map GL_OES_required_internalformat GL_OES_surfaceless_context GL_EXT_color_buffer_float GL_EXT_debug_label GL_EXT_sRGB_write_control GL_EXT_separate_shader_objects GL_EXT_shader_implicit_conversions GL_EXT_shader_integer_mix GL_EXT_base_instance GL_EXT_compressed_ETC1_RGB8_sub_texture GL_EXT_copy_image GL_EXT_draw_buffers_indexed GL_EXT_draw_elements_base_vertex GL_EXT_polygon_offset_clamp GL_EXT_primitive_bounding_box GL_EXT_shader_io_blocks GL_EXT_texture_border_clamp GL_EXT_texture_view GL_KHR_context_flush_control GL_NV_image_formats GL_NV_shader_noperspective_interpolation GL_OES_copy_ima ...
	Zeile  318: 2023-12-26 08:48:45.654 T:1027    error <general>: SETTINGS: execute # Command failed: /usr/bin/.rpi-eeprom-update.real -j -m "/tmp/tmpfk2ni_5d"
	Zeile  319: 2023-12-26 08:48:45.654 T:1027    error <general>: SETTINGS: execute # shell exit code: 1
	Zeile  320: 2023-12-26 08:48:45.654 T:1027    error <general>: SETTINGS: execute # START COMMAND OUTPUT:
	Zeile  387: 2023-12-26 11:54:33.834 T:1003    error <general>: Control 1000 in window 13001 has been asked to focus, but it can't
	Zeile 3234:                                                    Error: EGL_BAD_SURFACE
	Zeile 3236:                                                    Type: EGL_DEBUG_MSG_ERROR_KHR
	Zeile 3361: 2023-12-26 11:56:28.793 T:1010    debug <general>: ActiveAE::SyncStream - average error of -42.611937, start adjusting
	Zeile 3362: 2023-12-26 11:56:28.793 T:1010    debug <general>: ActiveAE::SyncStream - average error -0.007771 below threshold of 30.000000
	Zeile 3373: 2023-12-26 11:56:53.717 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3375: 2023-12-26 11:56:53.748 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3376: 2023-12-26 11:56:53.749 T:1285    debug <general>: ffmpeg[0x4a2a970]: [hls] keepalive request failed for 'https://delivery-node-rexesqooo91w0spw.voe-network.net/engine/hls2/01/06328/mg1dm95r6ati_,n,.urlset/seg-5-v1-a1.ts?t=U6dnU-QhSFhcInqH_-1YuxagGv2nodLod-Bcee-ul1U&s=1703588187&e=14400&f=31643498&node=delivery-node-rexesqooo91w0spw.voe-network.net&i=185.17&sp=2500&asn=8767' with error: 'Input/output error' when opening url, retrying with new connection
	Zeile 3406: 2023-12-26 11:57:01.494 T:1294    error <general>: ffmpeg[0x4883a90]: [aac] invalid band type
	Zeile 3408: 2023-12-26 11:57:01.494 T:1294    error <general>: ffmpeg[0x4883a90]: [aac] channel element 2.7 is not allocated
	Zeile 3418: 2023-12-26 11:57:26.835 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3420: 2023-12-26 11:57:26.896 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3421: 2023-12-26 11:57:26.897 T:1285    debug <general>: ffmpeg[0x4a2a970]: [hls] keepalive request failed for 'https://delivery-node-rexesqooo91w0spw.voe-network.net/engine/hls2/01/06328/mg1dm95r6ati_,n,.urlset/seg-9-v1-a1.ts?t=U6dnU-QhSFhcInqH_-1YuxagGv2nodLod-Bcee-ul1U&s=1703588187&e=14400&f=31643498&node=delivery-node-rexesqooo91w0spw.voe-network.net&i=185.17&sp=2500&asn=8767' with error: 'Input/output error' when opening url, retrying with new connection
	Zeile 3444: 2023-12-26 11:57:34.543 T:1294    error <general>: ffmpeg[0x4883a90]: [aac] Number of bands (61) exceeds limit (45).
	Zeile 3446: 2023-12-26 11:57:34.551 T:1294    error <general>: ffmpeg[0x4883a90]: [aac] Inconsistent channel configuration.
	Zeile 3447: 2023-12-26 11:57:34.551 T:1294    error <general>: ffmpeg[0x4883a90]: [aac] get_buffer() failed
	Zeile 3449: 2023-12-26 11:57:35.944 T:1294    debug <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:64075.896321, adjusted:33333.333333
	Zeile 3451: 2023-12-26 11:57:40.996 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3453: 2023-12-26 11:57:40.997 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3454: 2023-12-26 11:57:40.998 T:1285    debug <general>: ffmpeg[0x4a2a970]: [hls] keepalive request failed for 'https://delivery-node-rexesqooo91w0spw.voe-network.net/engine/hls2/01/06328/mg1dm95r6ati_,n,.urlset/seg-11-v1-a1.ts?t=U6dnU-QhSFhcInqH_-1YuxagGv2nodLod-Bcee-ul1U&s=1703588187&e=14400&f=31643498&node=delivery-node-rexesqooo91w0spw.voe-network.net&i=185.17&sp=2500&asn=8767' with error: 'Input/output error' when opening url, retrying with new connection
	Zeile 3476: 2023-12-26 11:57:50.293 T:1294    debug <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:52133.466889, adjusted:33333.333333
	Zeile 3488: 2023-12-26 11:58:20.034 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3490: 2023-12-26 11:58:20.126 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3491: 2023-12-26 11:58:20.127 T:1285    debug <general>: ffmpeg[0x4a2a970]: [hls] keepalive request failed for 'https://delivery-node-rexesqooo91w0spw.voe-network.net/engine/hls2/01/06328/mg1dm95r6ati_,n,.urlset/seg-15-v1-a1.ts?t=U6dnU-QhSFhcInqH_-1YuxagGv2nodLod-Bcee-ul1U&s=1703588187&e=14400&f=31643498&node=delivery-node-rexesqooo91w0spw.voe-network.net&i=185.17&sp=2500&asn=8767' with error: 'Input/output error' when opening url, retrying with new connection
	Zeile 3514: 2023-12-26 11:58:27.742 T:1294    error <general>: ffmpeg[0x4883a90]: [aac] Number of bands (46) exceeds limit (45).
	Zeile 3515: 2023-12-26 11:58:27.743 T:1294    error <general>: ffmpeg[0x4883a90]: [aac] channel element 2.8 is not allocated
	Zeile 3517: 2023-12-26 11:58:29.542 T:1294    debug <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:66111.897785, adjusted:33333.333333
	Zeile 3519: 2023-12-26 11:58:32.665 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3521: 2023-12-26 11:58:32.666 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3522: 2023-12-26 11:58:32.666 T:1285    debug <general>: ffmpeg[0x4a2a970]: [hls] keepalive request failed for 'https://delivery-node-rexesqooo91w0spw.voe-network.net/engine/hls2/01/06328/mg1dm95r6ati_,n,.urlset/seg-17-v1-a1.ts?t=U6dnU-QhSFhcInqH_-1YuxagGv2nodLod-Bcee-ul1U&s=1703588187&e=14400&f=31643498&node=delivery-node-rexesqooo91w0spw.voe-network.net&i=185.17&sp=2500&asn=8767' with error: 'Input/output error' when opening url, retrying with new connection
	Zeile 3547: 2023-12-26 11:58:46.879 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3549: 2023-12-26 11:58:46.880 T:1285    error <general>: ffmpeg[0x4a2a970]: [tls] error:00000000:lib(0)::reason(0)
	Zeile 3550: 2023-12-26 11:58:46.880 T:1285    debug <general>: ffmpeg[0x4a2a970]: [hls] keepalive request failed for 'https://delivery-node-rexesqooo91w0spw.voe-network.net/engine/hls2/01/06328/mg1dm95r6ati_,n,.urlset/seg-19-v1-a1.ts?t=U6dnU-QhSFhcInqH_-1YuxagGv2nodLod-Bcee-ul1U&s=1703588187&e=14400&f=31643498&node=delivery-node-rexesqooo91w0spw.voe-network.net&i=185.17&sp=2500&asn=8767' with error: 'Input/output error' when opening url, retrying with new connection
	Zeile 3572: 2023-12-26 11:58:55.193 T:1294    debug <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:60248.478294, adjusted:33333.333333
	Zeile 3574: 2023-12-26 11:58:57.242 T:1294    debug <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:51117.151374, adjusted:33333.333333
	Zeile 3699: 2023-12-26 11:59:03.354 T:1003    error <general>: Control 55 in window 10025 has been asked to focus, but it can't
	Zeile 3710:                                                    Error: EGL_BAD_SURFACE
	Zeile 3712:                                                    Type: EGL_DEBUG_MSG_ERROR_KHR
	Zeile 4821: 2023-12-26 11:59:28.695 T:1003    error <general>: Control 1000 in window 13000 has been asked to focus, but it can't

Display More

did someone experience same issues in the past? any ideas?

ty

**chewitt** · December 27, 2023 at 5:22 AM

Pastebin the full debug log not the snippet please (use "pastekodi" in the OS).

blackhades00 · December 29, 2023 at 1:02 PM

here is the request logfile:

https://paste.libreelec.tv/needed-muskox.log

thanks for your help

**chewitt** · December 29, 2023 at 2:14 PM

I'll feign suprise that the banned add-on doesn't give good playback. No further support.

Official:Forum rules/Banned add-ons - Official Kodi Wiki