Hello OSMC fam. I finally got around to purchasing and setting up a Vero 4K+ on a 55" OLED with surround sound. It’s absolutely beautiful and I thank you for such a great product. There is one issue however that has been perplexing me since I set everything up. When the Vero is idle for some time, say at least 1 hour, I am unable to play videos or music. Sometimes I can get out of it by rage clicking the back button on the remote to force the “playback failed” popup and then proceed as normal. Other times the GUI completely freezes and I have to power cycle. I have attached two debug snippets below from kodi.log while the issue was occurring for both video and audio. Below are a few more points that may help. Please let me know if there is anything I can test on my end. I’m comfortable with commands. Thank you!
- Using an HDMI splitter (one output to TV for video and one output to receiver for audio. This is the only way I can get full audio support. The issue still occurs if Vero is connected directly to TV)
- The issue seems to happen after the Vero is idle for a while (at least 1 hour)
- Both memory and CPU are hanging around 75% while issue is happening
- Vero is not warm to the touch
- Affects both video and audio files. Doesn’t seem to be format specific (mkv, mp4, mpg, mp3, and flac have been affected)
- Media is accessed through NFS mounted via fstab
- Sometimes I can get out of it by pressing the back button on the remote, then click the expand icon in the GUI and it pops the window “playback failed”. After acknowledging the error, everything is playable.
- Sometimes I have to pull the power because it’s completely frozen (Clock is frozen in upper right and GUI is unresponsive, SSH is still accessible)
- As a workaround, I’ve been executing systemctl restart mediacenter before each use
Video playback issue:
2021-12-07 09:40:29.702 T:6500 DEBUG <general>: CFileCache::Open - </mnt/NFS_mount/OSMC/Movies/Shang-Chi and the Legend of the Ten Rings (2021)/Shang.Chi.and.the.Legend.of.the.Ten.Rings.2021.2160p.UHD.BluRay.x265.mkv> opening
2021-12-07 09:40:30.017 T:6500 DEBUG <general>: CFileCache::Open - </mnt/NFS_mount/OSMC/Movies/Shang-Chi and the Legend of the Ten Rings (2021)/Shang.Chi.and.the.Legend.of.the.Ten.Rings.2021.2160p.UHD.BluRay.x265.mkv> source chunk size is 0, setting cache chunk size to 131072
2021-12-07 09:40:30.017 T:6500 DEBUG <general>: CFileCache::Open - </mnt/NFS_mount/OSMC/Movies/Shang-Chi and the Legend of the Ten Rings (2021)/Shang.Chi.and.the.Legend.of.the.Ten.Rings.2021.2160p.UHD.BluRay.x265.mkv> using double memory cache each sized 262440000 bytes
2021-12-07 09:40:30.017 T:6503 DEBUG <general>: Thread FileCache start, auto delete: false
2021-12-07 09:40:30.018 T:6500 DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-12-07 09:40:30.022 T:6500 DEBUG <general>: ScanForExternalSubtitles: END (total time: 4 ms)
2021-12-07 09:40:30.022 T:6500 INFO <general>: Creating Demuxer
2021-12-07 09:40:50.067 T:6500 DEBUG <general>: ffmpeg[0xb0b052d8X]: Format matroska,webm detected only with low score of 1, misdetection possible!
2021-12-07 09:40:50.067 T:6500 DEBUG <general>: Open - probing detected format [matroska,webm]
2021-12-07 09:40:56.714 T:6494 DEBUG <general>: Thread JobWorker 3359092928 terminating (autodelete)
2021-12-07 09:40:56.715 T:6502 DEBUG <general>: Thread JobWorker 3329855680 terminating (autodelete)
2021-12-07 09:40:56.723 T:6497 DEBUG <general>: Thread JobWorker 3263152320 terminating (autodelete)
2021-12-07 09:40:56.731 T:6501 DEBUG <general>: Thread JobWorker 3338248384 terminating (autodelete)
2021-12-07 09:41:00.090 T:6500 ERROR <general>: ffmpeg[0xb0b052d8X]: [matroska,webm] Read error at pos. 0 (0x0)
2021-12-07 09:41:00.090 T:6500 ERROR <general>: ffmpeg[0xb0b052d8X]: [matroska,webm] EBML header parsing failed
2021-12-07 09:41:00.090 T:6500 ERROR <general>: Open - Error, could not open file /mnt/NFS_mount/OSMC/Movies/Shang-Chi and the Legend of the Ten Rings (2021)/Shang.Chi.and.the.Legend.of.the.Ten.Rings.2021.2160p.UHD.BluRay.x265.mkv
2021-12-07 09:41:00.090 T:6500 ERROR <general>: OpenDemuxStream - Error creating demuxer
2021-12-07 09:41:00.090 T:6500 INFO <general>: CVideoPlayer::OnExit()
2021-12-07 09:41:00.092 T:6504 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-12-07 09:41:00.104 T:6504 INFO <general>: Deleting settings information for files /mnt/NFS_mount/OSMC/Movies/Shang-Chi and the Legend of the Ten Rings (2021)/Shang.Chi.and.the.Legend.of.the.Ten.Rings.2021.2160p.UHD.BluRay.x265.mkv
2021-12-07 09:41:00.107 T:6505 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-12-07 09:41:30.110 T:6504 DEBUG <general>: Thread JobWorker 3338248384 terminating (autodelete)
2021-12-07 09:41:30.111 T:6505 DEBUG <general>: Thread JobWorker 3263152320 terminating (autodelete)
Music playback issue:
2021-12-07 17:27:00.893 T:8459 DEBUG <general>: CFileCache::Open - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> opening
2021-12-07 17:27:00.893 T:7980 DEBUG <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2021-12-07 17:27:00.894 T:7980 DEBUG <general>: Keyboard: scancode: 0x1c, sym: 0x13, unicode: 0x000d, modifier: 0x0
2021-12-07 17:27:00.894 T:7980 DEBUG <general>: FreeVisualisation() done
2021-12-07 17:27:00.894 T:7980 DEBUG <general>: ADDON: Dll Initializing - Matrix
2021-12-07 17:27:00.894 T:7980 DEBUG <general>: SECTION:LoadDLL(/usr/lib/kodi/addons/visualization.matrix/visualization.matrix.so.19.0.0)
2021-12-07 17:27:00.894 T:7980 DEBUG <general>: Loading: /usr/lib/kodi/addons/visualization.matrix/visualization.matrix.so.19.0.0
2021-12-07 17:27:00.907 T:7980 DEBUG <general>: CMusicGUIInfo::InitCurrentItem(musicdb://albums/656/-2/4543.flac?albumid=656&xsp=%7b%22limit%22%3a15%2c%22order%22%3a%7b%22direction%22%3a%22descending%22%2c%22ignorefolders%22%3a0%2c%22method%22%3a%22playcount%22%7d%2c%22rules%22%3a%7b%22and%22%3a%5b%7b%22field%22%3a%22playcount%22%2c%22operator%22%3a%22greaterthan%22%2c%22value%22%3a%5b%220%22%5d%7d%5d%7d%2c%22type%22%3a%22albums%22%7d)
2021-12-07 17:27:00.909 T:8460 DEBUG <general>: PAPlayer::Process - Playback started
2021-12-07 17:27:00.920 T:7980 DEBUG <general>: SELECT albumview.*,albumartistview.* FROM albumview JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum WHERE albumview.idAlbum = 656 ORDER BY albumartistview.iOrder
2021-12-07 17:27:00.923 T:7980 DEBUG <general>: Loading additional tag info for file /mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac
2021-12-07 17:27:02.794 T:8459 DEBUG <general>: CFileCache::Open - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> source chunk size is 0, setting cache chunk size to 131072
2021-12-07 17:27:02.794 T:8459 DEBUG <general>: CFileCache::Open - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> using single memory cache sized 524880000 bytes
2021-12-07 17:27:02.795 T:8461 DEBUG <general>: Thread FileCache start, auto delete: false
2021-12-07 17:27:22.828 T:8459 DEBUG <general>: ffmpeg[0xaf41d5a8X]: Format flac detected only with low score of 1, misdetection possible!
2021-12-07 17:27:22.828 T:8459 DEBUG <general>: Open - probing detected format [flac]
2021-12-07 17:27:32.845 T:8459 DEBUG <general>: Open - avformat_find_stream_info starting
2021-12-07 17:27:32.845 T:8459 DEBUG <general>: ffmpeg[0xaf41d5a8X]: [flac] Could not find codec parameters for stream 0 (Audio: flac, 0 channels): unspecified sample format
2021-12-07 17:27:32.845 T:8459 DEBUG <general>: ffmpeg[0xaf41d5a8X]: [flac] Consider increasing the value for the 'analyzeduration' and 'probesize' options
2021-12-07 17:27:32.845 T:8459 DEBUG <general>: Open - av_find_stream_info finished
2021-12-07 17:27:32.846 T:8459 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-12-07 17:27:32.846 T:8459 INFO <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder flac
****Song never played, tried playing song again and then GUI froze for a few seconds and then finally played with log print below***
2021-12-07 17:29:30.830 T:7996 DEBUG <general>: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
2021-12-07 17:29:30.831 T:8464 DEBUG <general>: Thread Timer start, auto delete: false
2021-12-07 17:29:30.878 T:8464 DEBUG <general>: Thread Timer 3619156160 terminating
2021-12-07 17:30:08.514 T:8459 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-12-07 17:30:08.515 T:7980 DEBUG <general>: CPlayerGUIInfo::InitCurrentItem(musicdb://albums/656/-2/4543.flac?albumid=656&xsp=%7b%22limit%22%3a15%2c%22order%22%3a%7b%22direction%22%3a%22descending%22%2c%22ignorefolders%22%3a0%2c%22method%22%3a%22playcount%22%7d%2c%22rules%22%3a%7b%22and%22%3a%5b%7b%22field%22%3a%22playcount%22%2c%22operator%22%3a%22greaterthan%22%2c%22value%22%3a%5b%220%22%5d%7d%5d%7d%2c%22type%22%3a%22albums%22%7d)
2021-12-07 17:30:08.516 T:8461 DEBUG <general>: CFileCache::Process - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> cache completely reset for seek to position 14111279
2021-12-07 17:30:08.516 T:8459 DEBUG <general>: CFileCache::Seek - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> waiting for position 14241326
2021-12-07 17:30:08.531 T:8461 DEBUG <general>: CFileCache::Process - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> source read hit eof
2021-12-07 17:30:08.571 T:8050 DEBUG <general>: Twitch: Player: |onPlayBackStarted| isTwitch |False| SeekTime ||
2021-12-07 17:30:08.582 T:8461 DEBUG <general>: CFileCache::Process - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> cache completely reset for seek to position 69819
2021-12-07 17:30:08.583 T:8461 DEBUG <general>: CFileCache::Process - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> cache completely reset for seek to position 69633
2021-12-07 17:30:08.584 T:8461 DEBUG <general>: CFileCache::Process - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> cache completely reset for seek to position 69632
2021-12-07 17:30:08.584 T:8459 DEBUG <general>: SeekTime - seek ended up on time 0.000
2021-12-07 17:30:08.586 T:8459 ERROR <general>: Skipped 1 duplicate messages..
2021-12-07 17:30:08.586 T:8459 ERROR <general>: ffmpeg[0xaf41d5a8X]: [flac] invalid sync code
2021-12-07 17:30:08.586 T:8459 ERROR <general>: ffmpeg[0xaf41d5a8X]: [flac] invalid frame header
2021-12-07 17:30:08.586 T:8459 ERROR <general>: ffmpeg[0xaf41d5a8X]: [flac] decode_frame() failed
2021-12-07 17:30:08.616 T:7980 DEBUG <general>: ------ Window Init (DialogBusy.xml) ------
2021-12-07 17:30:08.618 T:7980 DEBUG <general>: Keyboard: scancode: 0x1c, sym: 0x13, unicode: 0x000d, modifier: 0x0
2021-12-07 17:30:08.618 T:7980 DEBUG <general>: HandleKey: return (0xf00d) pressed, action is Select
2021-12-07 17:30:08.618 T:7980 DEBUG <general>: Keyboard: scancode: 0x1c, sym: 0x13, unicode: 0x000d, modifier: 0x0
2021-12-07 17:30:08.667 T:8459 INFO <general>: AudioDecoder: File is queued
2021-12-07 17:30:08.669 T:8459 DEBUG <general>: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000
2021-12-07 17:30:08.674 T:7992 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-12-07 17:30:08.686 T:8459 INFO <general>: PAPlayer::PrepareStream - Ready
2021-12-07 17:30:08.693 T:8460 DEBUG <general>: OnAVStarted: CApplication::OnAVStarted
2021-12-07 17:30:08.696 T:7992 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-12-07 17:30:08.697 T:7993 INFO <general>: CActiveAESink::OpenSink - initialize sink
2021-12-07 17:30:08.702 T:7993 DEBUG <general>: CActiveAESink::OpenSink - trying to open device ALSA:default
2021-12-07 17:30:08.702 T:7993 INFO <general>: CAESinkALSA::Initialize - Requested layout: FL, FR
2021-12-07 17:30:08.702 T:7993 INFO <general>: CAESinkALSA::Initialize - set digital_codec 0
2021-12-07 17:30:08.702 T:7993 DEBUG <general>: CAESinkALSA::Initialize -- unmuting HDMI
2021-12-07 17:30:08.703 T:7993 INFO <general>: CAESinkALSA::Initialize - Attempting to open device "default"
2021-12-07 17:30:08.704 T:7980 DEBUG <general>: AddOnLog: visualization.matrix: Start 2 44100 32 The Wider Sun
2021-12-07 17:30:08.705 T:7980 DEBUG <general>: AddOnLog: visualization.matrix: Fragment shader header
#version 100
#extension GL_OES_standard_derivatives : enable
precision mediump float;
precision mediump int;
#define FragColor gl_FragColor
#ifndef texture
#define texture texture2D
#endif
const float cRNDSEED1 = 170.12;
const float cRNDSEED2 = 7572.1;
const float cINTENSITY = 1.0;
const float cMININTENSITY = 0.075;
const float cDISTORTFACTORX = 0.6;
const float cDISTORTFACTORY = 0.4;
const float cVIGNETTEINTENSITY = 0.05;
const float cDotSize = 4.000000;
const float cColumns = 240.000000;
const float cNoiseFluctuation = 0.010000;
const float cDistortThreshold = 0.300000;
const float cRainHighlights = 1.120000;
const vec3 cColor = vec3(0.200000,0.800000,1.000000);
const vec2 cResolution = vec2(32,260);
uniform sampler2D iChannel0;
uniform sampler2D iChannel1;
uniform sampler2D iChannel2;
#define dNoise
uniform float iTime;
float h11(float p)
{
return fract(20.12345+sin(p*cRNDSEED1)*cRNDSEED2);
}
float waveform(vec2 uv)
{
float wave = texture(iChannel0,vec2(uv.x*.15+.5,0.75)).x*.5 + uv.y;
return abs(smoothstep(.225,.275,wave) -.5);
}
#ifdef dNoise
float noise(vec2 gv)
{
return texture(iChannel2, (gv*.035431) + iTime*cNoiseFluctuation).x;
}
#endif
vec3 bw2col(float bw, vec2 uv)
{
float d = length(fract(uv*cColumns)-.5);
float peakcolor = smoothstep(.35,.0,d)*bw;
float basecolor = smoothstep(.85,.0,d)*bw;
return basecolor*cColor+peakcolor;
}
#ifdef dCrtCurve
vec2 getUV()
{
vec2 uv = (gl_FragCoord.xy-0.5*cResolution.xy)/cResolution.y;
uv = uv / (1.00 - length(uv*.1));
return uv;
}
#else
vec2 getUV()
{
vec2 uv = (gl_FragCoord.xy-0.5*cResolution.xy)/cResolution.y;
return uv;
}
#endif
2021-12-07 17:30:08.717 T:7993 INFO <general>: CAESinkALSA::Initialize - Opened device "default"
2021-12-07 17:30:08.718 T:7980 DEBUG <general>: AddOnLog: visualization.matrix: bits of precision: 13
2021-12-07 17:30:08.718 T:7980 DEBUG <general>: AddOnLog: visualization.matrix: creating texture /usr/share/kodi/addons/visualization.matrix//resources/textures/logo.png
2021-12-07 17:30:08.719 T:7993 INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2021-12-07 17:30:08.719 T:7993 INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4
2021-12-07 17:30:08.719 T:7993 DEBUG <general>: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192
2021-12-07 17:30:08.721 T:7993 DEBUG <general>: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192
2021-12-07 17:30:08.721 T:7993 DEBUG <general>: CAESinkALSA::InitializeHW - Setting timeout to 186 ms
2021-12-07 17:30:08.721 T:7993 INFO <general>: CAESinkALSA::Initialize - speaker layout 0
2021-12-07 17:30:08.722 T:7993 DEBUG <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2021-12-07 17:30:08.722 T:7993 DEBUG <general>: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2021-12-07 17:30:08.722 T:7993 DEBUG <general>: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: none) (CEA: 0)
2021-12-07 17:30:08.722 T:7993 DEBUG <general>: CActiveAESink::OpenSink - ALSA Initialized:
2021-12-07 17:30:08.722 T:7993 DEBUG <general>: Output Device : Default (AML-MESONAUDIO: HDMI, S/PDIF & analogue)
2021-12-07 17:30:08.722 T:7993 DEBUG <general>: Sample Rate : 44100
2021-12-07 17:30:08.722 T:7993 DEBUG <general>: Sample Format : AE_FMT_S24NE4
2021-12-07 17:30:08.722 T:7993 DEBUG <general>: Channel Count : 2
2021-12-07 17:30:08.723 T:7993 DEBUG <general>: Channel Layout: FL, FR
2021-12-07 17:30:08.723 T:7993 DEBUG <general>: Frames : 2048
2021-12-07 17:30:08.723 T:7993 DEBUG <general>: Frame Size : 8
2021-12-07 17:30:08.732 T:7980 DEBUG <general>: AddOnLog: visualization.matrix: creating texture /usr/share/kodi/addons/visualization.matrix//resources/textures/noise.png
2021-12-07 17:30:08.737 T:7980 DEBUG <general>: AddOnLog: visualization.matrix: Fragment shader header
#version 100
#extension GL_OES_standard_derivatives : enable
precision mediump float;
precision mediump int;
#define FragColor gl_FragColor
#ifndef texture
#define texture texture2D
#endif
const float cRNDSEED1 = 170.12;
const float cRNDSEED2 = 7572.1;
const float cINTENSITY = 1.0;
const float cMININTENSITY = 0.075;
const float cDISTORTFACTORX = 0.6;
const float cDISTORTFACTORY = 0.4;
const float cVIGNETTEINTENSITY = 0.05;
const float cDotSize = 4.000000;
const float cColumns = 240.000000;
const float cNoiseFluctuation = 0.010000;
const float cDistortThreshold = 0.300000;
const float cRainHighlights = 1.120000;
const vec3 cColor = vec3(0.200000,0.800000,1.000000);
const vec2 cResolution = vec2(1920,1080);
uniform sampler2D iChannel0;
uniform sampler2D iChannel1;
uniform sampler2D iChannel2;
#define dNoise
uniform float iTime;
float h11(float p)
{
return fract(20.12345+sin(p*cRNDSEED1)*cRNDSEED2);
}
float waveform(vec2 uv)
{
float wave = texture(iChannel0,vec2(uv.x*.15+.5,0.75)).x*.5 + uv.y;
return abs(smoothstep(.225,.275,wave) -.5);
}
#ifdef dNoise
float noise(vec2 gv)
{
return texture(iChannel2, (gv*.035431) + iTime*cNoiseFluctuation).x;
}
#endif
vec3 bw2col(float bw, vec2 uv)
{
float d = length(fract(uv*cColumns)-.5);
float peakcolor = smoothstep(.35,.0,d)*bw;
float basecolor = smoothstep(.85,.0,d)*bw;
return basecolor*cColor+peakcolor;
}
#ifdef dCrtCurve
vec2 getUV()
{
vec2 uv = (gl_FragCoord.xy-0.5*cResolution.xy)/cResolution.y;
uv = uv / (1.00 - length(uv*.1));
return uv;
}
#else
vec2 getUV()
{
vec2 uv = (gl_FragCoord.xy-0.5*cResolution.xy)/cResolution.y;
return uv;
}
#endif
2021-12-07 17:30:08.760 T:7980 DEBUG <general>: ------ Window Deinit (DialogBusy.xml) ------
2021-12-07 17:30:08.761 T:7980 DEBUG <general>: AddOnLog: visualization.matrix: Updating album art
2021-12-07 17:30:10.424 T:8461 DEBUG <general>: CFileCache::Process - </mnt/NFS_mount/OSMC/Music/Jon Hopkins - Insides (2009)/01 - Jon Hopkins - The Wider Sun.flac> source read hit eof
2021-12-07 17:30:38.688 T:8459 DEBUG <general>: Thread JobWorker 3514708160 terminating (autodelete)
