New Viro 4k+ Streams hanging

#1

Just got my new Vero 4k+ and it is much “snappier” than my old RP. Cool. Very nice little bit of kit!!

I am on the road and my family is reporting it is “hanging” when watching streams for a long time (like Sky News or BBC).

We just use OSMC for Plex (running on an unRAID server) and News (BBC, Sky, etc.). I did a clean install on the Vero 4k+ and then restored my backup from my RP. I also deleted and reinstalled Plex. Wonder if I should do the same for Sky and BBC?

There seem to be some thumbnail issues (last night when watching Plex) and then after firing up Sky this morning, they say it hung.

Like I said, I am on the road. I got my son to grab bits of a log to share if that helps? Like I said, on the road and the family is bugging me. :slightly_smiling_face: More logs if needed when I get home. Thanks!

=========

2019-09-09 21:46:58.763 T:4071505920 WARNING: CGUIMediaWindow::OnMessage - updating in progress
2019-09-09 21:47:17.832 T:3522425568   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/8/8eece065.jpg
2019-09-09 21:47:52.508 T:4071505920   ERROR: Control 50 in window 10001 has been asked to focus, but it can't
2019-09-09 21:47:55.757 T:3083743968   ERROR: GetDirectory - Error getting plugin://script.plex/
2019-09-09 21:47:55.764 T:4071505920   ERROR: CGUIMediaWindow::GetDirectory(plugin://script.plex/) failed
2019-09-09 21:48:08.775 T:3794784992   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/1/14c90f8c.jpg
2019-09-09 21:48:14.034 T:3522425568   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/d/d0511a96.jpg
2019-09-09 21:48:14.036 T:3629118176   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/0/02346ee9.jpg
2019-09-09 21:48:14.043 T:3405877984   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/d/d0511a96.jpg
2019-09-09 21:48:14.052 T:3794784992   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/0/02346ee9.jpg
2019-09-09 21:48:21.561 T:4071505920  NOTICE: VideoPlayer::OpenFile: https://192-168-1-115.46794029a335401ead998d82edd0bcb5.plex.direct:32400/library/parts/57792/1567623913/file.mkv?X-Plex-Token=sqFzs1whrgHUg9HyQULE&X-Plex-Client-Identifier=3b2d329e-ded3-4c2e-873c-a9ac0bf8bd9c&X-Plex-Platform=Chrome
2019-09-09 21:48:21.566 T:3278349024  NOTICE: Creating InputStream
2019-09-09 21:48:21.695 T:3278349024  NOTICE: Creating Demuxer
2019-09-09 21:48:21.922 T:3278349024  NOTICE: Opening stream: 0 source: 256
2019-09-09 21:48:21.922 T:3278349024  NOTICE: CVideoPlayerVideo::OpenStream - Creating codec: 27
2019-09-09 21:48:21.940 T:3278349024  NOTICE: Creating video thread
2019-09-09 21:48:21.940 T:3218027232  NOTICE: running thread: video_thread
2019-09-09 21:48:22.127 T:3278349024  NOTICE: Opening stream: 1 source: 256
2019-09-09 21:48:22.127 T:3278349024  NOTICE: Finding audio codec for: 86018
2019-09-09 21:48:22.129 T:3278349024  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2019-09-09 21:48:22.129 T:3278349024  NOTICE: Creating audio thread
2019-09-09 21:48:22.129 T:3201241824  NOTICE: running thread: CVideoPlayerAudio::Process()
2019-09-09 21:48:22.147 T:3201241824  NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 44100, no pass-through)
2019-09-09 21:48:22.161 T:3218027232  NOTICE: CAMLCodec::OpenDecoder - using V4L2 pts format: 64Bit
2019-09-09 22:30:27.930 T:3278349024  NOTICE: CVideoPlayer::OnExit()
2019-09-09 22:30:27.930 T:3278349024  NOTICE: VideoPlayer: eof, waiting for queues to empty
2019-09-09 22:30:27.930 T:3278349024  NOTICE: Closing stream player 1
2019-09-09 22:30:27.930 T:3278349024  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
2019-09-09 22:30:27.930 T:3278349024  NOTICE: Waiting for audio thread to exit
2019-09-09 22:30:27.930 T:3201241824   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2019-09-09 22:30:27.930 T:3201241824  NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-09-09 22:30:27.930 T:3278349024  NOTICE: Closing audio device
2019-09-09 22:30:28.314 T:3278349024  NOTICE: Deleting audio codec
2019-09-09 22:30:28.315 T:3278349024  NOTICE: Closing stream player 2
2019-09-09 22:30:28.315 T:3278349024  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
2019-09-09 22:30:28.921 T:3278349024  NOTICE: waiting for video thread to exit
2019-09-09 22:30:28.921 T:3218027232  NOTICE: thread end: video_thread
2019-09-09 22:30:28.921 T:3278349024  NOTICE: deleting video codec
2019-09-09 22:30:29.242 T:4071505920  NOTICE: CVideoPlayer::CloseFile()
2019-09-09 22:30:29.242 T:4071505920  NOTICE: VideoPlayer: waiting for threads to exit
2019-09-09 22:30:29.242 T:4071505920  NOTICE: VideoPlayer: finished waiting
2019-09-09 22:30:29.242 T:4071505920  NOTICE: CVideoPlayer::CloseFile()
2019-09-09 22:30:29.242 T:4071505920  NOTICE: VideoPlayer: waiting for threads to exit
2019-09-09 22:30:29.242 T:4071505920  NOTICE: VideoPlayer: finished waiting
2019-09-09 22:30:29.287 T:3226419936   ERROR: EXCEPTION: XBMC is not playing any media file
2019-09-09 22:30:29.792 T:3278349024   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/3/3005a487.jpg
2019-09-09 22:30:30.730 T:3083743968   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/a/aa98d038.jpg
2019-09-09 22:30:30.732 T:3092136672   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/3/3a34a5d2.jpg
2019-09-09 22:42:55.417 T:3822031584  NOTICE: plugin.audio.spotify --> Logged in to Spotify - Username: dave.zack
2019-09-09 22:45:26.353 T:3218027232   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/0/0c451985.jpg
2019-09-09 22:45:26.469 T:3278349024   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/8/8c5986ba.jpg
2019-09-09 22:45:26.480 T:3201241824   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/9/98e8c60b.jpg
2019-09-09 22:45:26.490 T:3629118176   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/3/3fbd8a83.jpg
2019-09-09 22:45:26.905 T:3218027232   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/e/ed10fa85.jpg
2019-09-09 22:45:28.118 T:4071505920   ERROR: Control 1001 in window 13003 has been asked to focus, but it can't
2019-09-09 22:45:36.280 T:3201241824   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/8/8eece065.jpg
2019-09-09 22:46:48.308 T:3522425568   ERROR: Previous line repeats 3 times.
2019-09-09 22:46:48.309 T:3522425568  NOTICE: [plugin.video.youtube] Running: YouTube (6.5.1) on Leia (Kodi-18.3) with Python 2.7.13
                                            	Path: /play/
                                            	Params: {'video_id': 'siyW0GOBtbo'}
2019-09-09 22:46:51.336 T:4071505920  NOTICE: VideoPlayer::OpenFile: plugin://plugin.video.skynews/?url=&mode=0&name=Live
2019-09-09 22:46:51.392 T:3226419936  NOTICE: Creating InputStream
2019-09-09 22:46:51.930 T:3226419936  NOTICE: Creating Demuxer
2019-09-09 22:46:53.257 T:3226419936  NOTICE: Opening stream: 1 source: 256
2019-09-09 22:46:53.258 T:3226419936  NOTICE: CVideoPlayerVideo::OpenStream - Creating codec: 27
2019-09-09 22:46:53.265 T:3226419936  NOTICE: Creating video thread
2019-09-09 22:46:53.265 T:3100529376  NOTICE: running thread: video_thread
2019-09-09 22:46:53.432 T:3226419936  NOTICE: Opening stream: 0 source: 256
2019-09-09 22:46:53.432 T:3226419936  NOTICE: Finding audio codec for: 86018
2019-09-09 22:46:53.434 T:3226419936  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2019-09-09 22:46:53.434 T:3226419936  NOTICE: Creating audio thread
2019-09-09 22:46:53.434 T:3243205344  NOTICE: running thread: CVideoPlayerAudio::Process()
2019-09-09 22:46:53.445 T:3243205344  NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
2019-09-09 22:46:53.720 T:3100529376  NOTICE: CAMLCodec::OpenDecoder - using V4L2 pts format: 64Bit
2019-09-09 22:48:26.067 T:4071505920  NOTICE: CVideoPlayer::CloseFile()
2019-09-09 22:48:26.068 T:4071505920  NOTICE: VideoPlayer: waiting for threads to exit
2019-09-09 22:48:26.125 T:3226419936  NOTICE: CVideoPlayer::OnExit()
2019-09-09 22:48:26.126 T:3226419936  NOTICE: Closing stream player 1
2019-09-09 22:48:26.126 T:3226419936  NOTICE: Waiting for audio thread to exit
2019-09-09 22:48:26.139 T:3243205344  NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-09-09 22:48:26.139 T:3226419936  NOTICE: Closing audio device
2019-09-09 22:48:26.184 T:3226419936  NOTICE: Deleting audio codec
2019-09-09 22:48:26.184 T:3226419936  NOTICE: Closing stream player 2
2019-09-09 22:48:26.184 T:3226419936  NOTICE: waiting for video thread to exit
2019-09-09 22:48:26.192 T:3100529376  NOTICE: thread end: video_thread
2019-09-09 22:48:26.194 T:3226419936  NOTICE: deleting video codec
2019-09-09 22:48:26.289 T:4071505920  NOTICE: VideoPlayer: finished waiting
2019-09-09 22:48:26.289 T:4071505920  NOTICE: CVideoPlayer::CloseFile()
2019-09-09 22:48:26.289 T:4071505920  NOTICE: VideoPlayer: waiting for threads to exit
2019-09-09 22:48:26.289 T:4071505920  NOTICE: VideoPlayer: finished waiting
2019-09-09 22:48:29.192 T:3243205344   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/8/8eece065.jpg
2019-09-09 22:48:33.287 T:4071505920  NOTICE: VideoPlayer::OpenFile: plugin://plugin.video.iplayerwww/?url=bbc_news24&mode=203&name=BBC+News+Channel&iconimage=%2Fhome%2Fosmc%2F.kodi%2Faddons%2Fplugin.video.iplayerwww%2Fmedia%2Fbbc_news24.png&description=&subtitles_url=&logged_in=False&time=1555675666.47
2019-09-09 22:48:33.302 T:3218027232  NOTICE: Creating InputStream
2019-09-09 22:48:33.422 T:3218027232  NOTICE: Creating Demuxer
2019-09-09 22:48:33.732 T:3218027232  NOTICE: Opening stream: 1 source: 256
2019-09-09 22:48:33.732 T:3218027232  NOTICE: CVideoPlayerVideo::OpenStream - Creating codec: 27
2019-09-09 22:48:33.733 T:3218027232  NOTICE: Creating video thread
2019-09-09 22:48:33.733 T:3514032864  NOTICE: running thread: video_thread
2019-09-09 22:48:33.958 T:3218027232  NOTICE: Opening stream: 0 source: 256
2019-09-09 22:48:33.958 T:3218027232  NOTICE: Finding audio codec for: 86018
2019-09-09 22:48:33.960 T:3218027232  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2019-09-09 22:48:33.960 T:3218027232  NOTICE: Creating audio thread
2019-09-09 22:48:33.960 T:3083743968  NOTICE: running thread: CVideoPlayerAudio::Process()
2019-09-09 22:48:33.971 T:3083743968  NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
2019-09-09 22:48:34.277 T:3514032864  NOTICE: CAMLCodec::OpenDecoder - using V4L2 pts format: 64Bit
2019-09-09 23:41:56.749 T:3822031584  NOTICE: plugin.audio.spotify --> Logged in to Spotify - Username: dave.zack
2019-09-10 00:11:21.940 T:3226419936   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/d/dd7e817b.jpg
2019-09-10 00:11:23.905 T:4071505920  NOTICE: CVideoPlayer::CloseFile()
2019-09-10 00:11:23.906 T:4071505920  NOTICE: VideoPlayer: waiting for threads to exit
2019-09-10 00:11:23.915 T:3218027232  NOTICE: CVideoPlayer::OnExit()
2019-09-10 00:11:23.915 T:3218027232  NOTICE: Closing stream player 1
2019-09-10 00:11:23.915 T:3218027232  NOTICE: Waiting for audio thread to exit
2019-09-10 00:11:23.922 T:3083743968  NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-09-10 00:11:23.922 T:3218027232  NOTICE: Closing audio device
2019-09-10 00:11:23.967 T:3218027232  NOTICE: Deleting audio codec
2019-09-10 00:11:23.967 T:3218027232  NOTICE: Closing stream player 2
2019-09-10 00:11:23.967 T:3218027232  NOTICE: waiting for video thread to exit
2019-09-10 00:11:23.970 T:3514032864  NOTICE: thread end: video_thread
2019-09-10 00:11:23.975 T:3218027232  NOTICE: deleting video codec
2019-09-10 00:11:23.979 T:4071505920  NOTICE: VideoPlayer: finished waiting
2019-09-10 00:11:23.979 T:4071505920  NOTICE: CVideoPlayer::CloseFile()
2019-09-10 00:11:23.979 T:4071505920  NOTICE: VideoPlayer: waiting for threads to exit
2019-09-10 00:11:23.979 T:4071505920  NOTICE: VideoPlayer: finished waiting
2019-09-10 00:40:58.013 T:3822031584  NOTICE: plugin.audio.spotify --> Logged in to Spotify - Username: dave.zack
2019-09-10 09:19:26.936 T:3514069728  NOTICE: Previous line repeats 8 times.
2019-09-10 09:19:26.936 T:3514069728   ERROR: DoWork - Direct texture file loading failed for special://masterprofile/Thumbnails/8/8eece065.jpg
2019-09-10 09:19:29.225 T:3226419936 WARNING: Attempt to use invalid handle 6
2019-09-10 09:19:32.153 T:2860176096 WARNING: Previous line repeats 5 times.
2019-09-10 09:19:32.153 T:2860176096  NOTICE: [plugin.video.youtube] Running: YouTube (6.5.1) on Leia (Kodi-18.3) with Python 2.7.13
                                            	Path: /play/
                                            	Params: {'video_id': 'siyW0GOBtbo'}
2019-09-10 09:19:35.271 T:4071505920  NOTICE: VideoPlayer::OpenFile: plugin://plugin.video.skynews/?url=&mode=0&name=Live
2019-09-10 09:19:35.340 T:2990437088  NOTICE: Creating InputStream
2019-09-10 09:19:35.631 T:2990437088  NOTICE: Creating Demuxer
2019-09-10 09:19:37.079 T:2990437088  NOTICE: Opening stream: 1 source: 256
2019-09-10 09:19:37.079 T:2990437088  NOTICE: CVideoPlayerVideo::OpenStream - Creating codec: 27
2019-09-10 09:19:37.085 T:2990437088  NOTICE: Creating video thread
2019-09-10 09:19:37.085 T:3007222496  NOTICE: running thread: video_thread
2019-09-10 09:19:37.086 T:2990437088  NOTICE: Opening stream: 0 source: 256
2019-09-10 09:19:37.086 T:2990437088  NOTICE: Finding audio codec for: 86018
2019-09-10 09:19:37.087 T:2990437088  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2019-09-10 09:19:37.087 T:2990437088  NOTICE: Creating audio thread
2019-09-10 09:19:37.087 T:3015615200  NOTICE: running thread: CVideoPlayerAudio::Process()
2019-09-10 09:19:37.101 T:3015615200  NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
2019-09-10 09:19:37.305 T:3007222496  NOTICE: CAMLCodec::OpenDecoder - using V4L2 pts format: 64Bit
2019-09-10 09:19:37.415 T:4029674208 WARNING: ActiveAE - large audio sync error: -5921.070602
2019-09-10 09:19:37.416 T:4029674208 WARNING: ActiveAE - large audio sync error: -5922.793060
2019-09-10 09:19:37.417 T:4029674208 WARNING: ActiveAE - large audio sync error: -5923.142977
2019-09-10 09:19:37.418 T:4029674208 WARNING: ActiveAE - large audio sync error: -5923.721602
2019-09-10 09:19:37.497 T:4029674208 WARNING: ActiveAE - large audio sync error: -6131.751768
2019-09-10 09:19:37.541 T:4029674208 WARNING: ActiveAE - large audio sync error: -6131.491602
2019-09-10 09:19:37.541 T:4029674208 WARNING: ActiveAE - large audio sync error: -6088.491935
2019-09-10 09:19:37.541 T:4029674208 WARNING: ActiveAE - large audio sync error: -6046.491102
2019-09-10 09:19:37.541 T:4029674208 WARNING: ActiveAE - large audio sync error: -6003.490727
2019-09-10 09:19:37.541 T:4029674208 WARNING: ActiveAE - large audio sync error: -5960.490477
2019-09-10 09:19:37.541 T:4029674208 WARNING: ActiveAE - large audio sync error: -5918.490602
2019-09-10 09:19:37.542 T:4029674208 WARNING: ActiveAE - large audio sync error: -5875.490269
2019-09-10 09:19:37.542 T:4029674208 WARNING: ActiveAE - large audio sync error: -5832.490310
2019-09-10 09:19:37.542 T:4029674208 WARNING: ActiveAE - large audio sync error: -5790.490269
2019-09-10 09:19:37.542 T:4029674208 WARNING: ActiveAE - large audio sync error: -5747.490394
2019-09-10 09:19:37.542 T:4029674208 WARNING: ActiveAE - large audio sync error: -5704.490310
2019-09-10 09:19:37.583 T:4029674208 WARNING: ActiveAE - large audio sync error: -5662.913145
2019-09-10 09:19:37.626 T:4029674208 WARNING: ActiveAE - large audio sync error: -5619.661560
2019-09-10 09:19:37.668 T:4029674208 WARNING: ActiveAE - large audio sync error: -5576.435476
2019-09-10 09:19:37.711 T:4029674208 WARNING: ActiveAE - large audio sync error: -5534.519977
2019-09-10 09:19:37.712 T:4029674208 WARNING: ActiveAE - large audio sync error: -5491.518977
2019-09-10 09:19:37.712 T:4029674208 WARNING: ActiveAE - large audio sync error: -5448.518894
2019-09-10 09:19:37.712 T:4029674208 WARNING: ActiveAE - large audio sync error: -5406.518644
2019-09-10 09:19:37.713 T:4029674208 WARNING: ActiveAE - large audio sync error: -5363.518769
2019-09-10 09:19:37.713 T:4029674208 WARNING: ActiveAE - large audio sync error: -5320.519060
2019-09-10 09:19:37.713 T:4029674208 WARNING: ActiveAE - large audio sync error: -5278.518727
2019-09-10 09:19:37.713 T:4029674208 WARNING: ActiveAE - large audio sync error: -5235.518811
2019-09-10 09:19:37.714 T:4029674208 WARNING: ActiveAE - large audio sync error: -5192.518394
2019-09-10 09:19:37.714 T:4029674208 WARNING: ActiveAE - large audio sync error: -5150.518644
2019-09-10 09:19:37.754 T:4029674208 WARNING: ActiveAE - large audio sync error: -5150.583811
2019-09-10 09:19:37.754 T:4029674208 WARNING: ActiveAE - large audio sync error: -5107.583395
2019-09-10 09:19:37.754 T:4029674208 WARNING: ActiveAE - large audio sync error: -5065.583311
2019-09-10 09:19:37.755 T:4029674208 WARNING: ActiveAE - large audio sync error: -5022.583186
2019-09-10 09:19:45.515 T:3015615200  NOTICE: CVideoPlayerAudio::Process - stream stalled pts:15.061 clock:15.066
2019-09-10 09:20:01.776 T:4071505920  NOTICE: CVideoPlayer::CloseFile()
2019-09-10 09:20:01.776 T:4071505920  NOTICE: VideoPlayer: waiting for threads to exit
2019-09-10 09:20:01.786 T:2990437088  NOTICE: CVideoPlayer::OnExit()
2019-09-10 09:20:01.786 T:2990437088  NOTICE: Closing stream player 1
2019-09-10 09:20:01.786 T:2990437088  NOTICE: Waiting for audio thread to exit
2019-09-10 09:20:01.792 T:3015615200  NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-09-10 09:20:01.792 T:2990437088  NOTICE: Closing audio device
2019-09-10 09:20:01.792 T:2990437088  NOTICE: Deleting audio codec
2019-09-10 09:20:01.798 T:2990437088  NOTICE: Closing stream player 2
2019-09-10 09:20:01.798 T:2990437088  NOTICE: waiting for video thread to exit
#2

I think we are going to need debug logs submitted properly.

To get a better understanding of the problem you are experiencing we need more information from you. The best way to get this information is for you to upload logs that demonstrate your problem. You can learn more about how to submit a useful support request here.

Depending on the used skin you have to set the settings-level to standard or higher, in summary:

  • enable debug logging at settings->system->logging

  • reboot the OSMC device

  • reproduce the issue

  • upload the log set either using the Log Uploader method within the My OSMC menu in the GUI or the ssh method invoking command grab-logs -A

  • publish the provided URL from the log set upload, here

Thanks for your understanding. We hope that we can help you get up and running again shortly.

#3

Thanks. When I get home this weekend, will do a clean install and try to reproduce and send complete logs. I am also restoring my old RP settings and wonder if this could be the cause? OSMC did say the restore was incomplete…so will try a clean install. Thx.

#4

Don’t copy over guisettings.xml if you can do