I’m having annoying issue with my new Vero 4K box and DVBlink channel loading. Randomly channel loading and changing is few minutes. Old Wetek Libreelec loads and changes channel in few seconds, so does DVBlink viewer in Windows 10. TV tuner is connected to my Synology NAS. Both devices (NAS and Vero 4K) are connected via network cable. Here the log when Vero loads channel longer than i would like(few minutes):
14:33:48.879 T:4111630336 NOTICE: VideoPlayer: Opening: pvr://channels/tv/All channels/pvr.dvblink_100.pvr
14:33:48.879 T:4111630336 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
14:33:48.880 T:3399078896 NOTICE: Creating InputStream
14:33:52.623 T:3399078896 NOTICE: Creating Demuxer
14:33:52.639 T:3399078896 NOTICE: Opening stream: 0 source: 256
14:33:52.639 T:3399078896 NOTICE: Creating video codec with codec id: 28
14:33:52.639 T:3399078896 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
14:33:52.641 T:3399078896 NOTICE: Creating video thread
14:33:52.641 T:3271447536 NOTICE: running thread: video_thread
14:33:52.641 T:3399078896 NOTICE: Opening stream: 1 source: 256
14:33:52.641 T:3399078896 NOTICE: Finding audio codec for: 86017
14:33:52.642 T:3399078896 NOTICE: Creating audio thread
14:33:52.642 T:3247711216 NOTICE: running thread: CVideoPlayerAudio::Process()
14:33:52.642 T:3399078896 NOTICE: Opening stream: 3 source: 256
14:33:53.104 T:3399078896 NOTICE: Opening stream: 0 source: 256
14:33:53.104 T:3399078896 NOTICE: Creating video codec with codec id: 28
14:33:53.106 T:3399078896 ERROR: Unable to load libamplayer.so, reason: libamplayer.so: cannot open shared object file: No such file or directory
14:33:53.106 T:3399078896 WARNING: CAMLCodec::CAMLCodec libamplayer.so not found, trying libamcodec.so instead
14:33:53.111 T:3399078896 NOTICE: Opening stream: 1 source: 256
14:33:53.111 T:3399078896 NOTICE: Closing stream player 3
14:33:53.113 T:3399078896 NOTICE: Opening stream: 3 source: 256
14:33:53.149 T:3271447536 NOTICE: CAMLCodec::OpenDecoder - using V4L2 pts format: 64Bit
14:33:53.169 T:3247711216 NOTICE: Creating audio stream (codec id: 86017, channels: 2, sample rate: 48000, no pass-through)
14:34:01.717 T:3399078896 WARNING: VideoPlayer::Sync - stream player video does not start, flushing buffers
14:35:24.106 T:4111630336 WARNING: Previous line repeats 10 times.
14:35:24.106 T:4111630336 NOTICE: NFS is idle. Closing the remaining connections.
14:35:30.368 T:3399078896 WARNING: VideoPlayer::Sync - stream player video does not start, flushing buffers
14:37:09.028 T:3271447536 WARNING: Previous line repeats 12 times.
14:37:09.029 T:3271447536 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
14:37:13.869 T:4111630336 WARNING: Previous line repeats 1 times.
14:37:13.869 T:4111630336 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (22) (weight: 0.000)
I guess there are high chances for the team to find a solution if you could provide debug enabled logs. Also if you add this into your post instead of using the upload feature suggest you use the pre-formatted text button </>
Later tonight i will get extra logs.
And also suggest to check the network throughput (with iperf) to ensure that there is no issue with the switch port or anything like that.
There is no problem with network. Vero 4K copy speed from NAS is 11.9MB/s, which is maximum for 100Mbit cable connection. Also every other media like movies, music works without an issue. Other clients(as mentioned before) work also fine.
Hi Teet
I think you emailed me about this a couple of days ago. If you can play recordings OK, but not
LiveTV via the DVBLink add-on, there could be an issue with the add-on.
I am sure some other DVBLink users may be able to advise.
Sam
Recordings start instantly! Channel loading/changing still takes few minutes(randomly, sometimes it loads normally(few seconds). All other clients load tv quickly(Android + Kodi via DVBlink adddon, DVBlink viewer, VLC). What logs are needed to investigate problem further on?
Debug logs showing slow startup time.
Is it always the same channel that doesn’t work?
What do you see when it’s loading
Different channels(also many are on different MUXes). During slow load i see Kodi loading circle going 0-100 too many times :).
Problems with OSMC and DVBlink go on! After july update i get only still image after launching tv. Audio is fine. Here is the log: 16:53:05.355 T:4109619200 NOTICE: VideoPlayer: Opening: pvr://channels/tv/All channels/pvr.dvblink_100.pvr 16:53:05.355 T:4109619200 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:53:05.355 T:3922457584 NOTICE: Creating InputStream 16:53:09.068 T:3922457584 NOTICE: Creating Demuxer 16:53:09.084 T:3922457584 NOTICE: Opening stream: 0 source: 256 16:53:09.085 T:3922457584 NOTICE: Creating video codec with codec id: 28 16:53:09.085 T:3922457584 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:53:09.087 T:3922457584 NOTICE: Creating video thread 16:53:09.087 T:3515585520 NOTICE: running thread: video_thread 16:53:09.087 T:3922457584 NOTICE: Opening stream: 1 source: 256 16:53:09.087 T:3922457584 NOTICE: Finding audio codec for: 86017 16:53:09.088 T:3922457584 NOTICE: Creating audio thread 16:53:09.088 T:3473073136 NOTICE: running thread: CVideoPlayerAudio::Process() 16:53:09.088 T:3922457584 NOTICE: Opening stream: 3 source: 256 16:53:09.587 T:3922457584 NOTICE: Opening stream: 0 source: 256 16:53:09.587 T:3922457584 NOTICE: Creating video codec with codec id: 28 16:53:09.589 T:3922457584 ERROR: Unable to load libamplayer.so, reason: libamplayer.so: cannot open shared object file: No such file or directory 16:53:09.590 T:3922457584 WARNING: CAMLCodec::CAMLCodec libamplayer.so not found, trying libamcodec.so instead 16:53:09.594 T:3922457584 NOTICE: Opening stream: 1 source: 256 16:53:09.594 T:3922457584 NOTICE: Closing stream player 3 16:53:09.594 T:3922457584 NOTICE: Opening stream: 3 source: 256 16:53:09.604 T:3473073136 NOTICE: Creating audio stream (codec id: 86017, channels: 2, sample rate: 48000, no pass-through) 16:53:09.628 T:3515585520 NOTICE: CAMLCodec::OpenDecoder - using V4L2 pts format: 64Bit 16:53:19.363 T:3515585520 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:53:22.464 T:4109619200 WARNING: Previous line repeats 1 times. 16:53:22.464 T:4109619200 NOTICE: CVideoPlayer::CloseFile() 16:53:22.464 T:4109619200 NOTICE: VideoPlayer: waiting for threads to exit 16:53:22.465 T:3922457584 NOTICE: CVideoPlayer::OnExit() 16:53:22.465 T:3922457584 NOTICE: Closing stream player 1 16:53:22.465 T:3922457584 NOTICE: Waiting for audio thread to exit 16:53:22.465 T:3922457584 NOTICE: Waiting for audio thread to exit 16:53:22.518 T:3473073136 NOTICE: thread end: CVideoPlayerAudio::OnExit() 16:53:22.518 T:3922457584 NOTICE: Closing audio device 16:53:22.604 T:3922457584 NOTICE: Deleting audio codec 16:53:22.604 T:3922457584 NOTICE: Closing stream player 2 16:53:22.604 T:3515585520 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 16:53:22.604 T:3515585520 NOTICE: thread end: video_thread 16:53:22.604 T:3922457584 NOTICE: waiting for video thread to exit 16:53:22.604 T:3922457584 NOTICE: deleting video codec 16:53:22.615 T:3922457584 NOTICE: Closing stream player 3 16:53:23.393 T:4109619200 NOTICE: VideoPlayer: finished waiting 16:53:23.433 T:4109619200 NOTICE: CVideoPlayer::CloseFile() 16:53:23.433 T:4109619200 NOTICE: VideoPlayer: waiting for threads to exit 16:53:23.433 T:4109619200 NOTICE: VideoPlayer: finished waiting 16:53:23.433 T:4109619200 NOTICE: CVideoPlayer::CloseFile() 16:53:23.433 T:4109619200 NOTICE: VideoPlayer: waiting for threads to exit 16:53:23.433 T:4109619200 NOTICE: VideoPlayer: finished waiting
Edit:
Got normal picture back. Removed old settings, Under PVR & Live TV -> Playback -> Fallback framerate 50Hz(my display has set to 50Hz also). I will update if there is any change.
Channel loading and changing seems also fine right now.
Don’t remember if I asked; so if I did, sorry, but do you get this issue with hardware acceleration disabled?
Does echo 4 > /sys/module/amvdec_h264/parameters/dec_control help/
Everything worked fine until july update. Now tv channels are loading, but no sound, picture as about half framerate and has buffering problem every about 10 sec. Tried DVBlink viewer and my laptop with Kodi, everyhing works as expected. Tried to restart Kodi, DVBlink server etc, no luck. If same channel is recorded, recording plays just fine.
Heres the log: 09:48:07.036 T:4114014208 NOTICE: VideoPlayer: Opening: pvr://channels/tv/All channels/pvr.dvblink_100.pvr 09:48:07.036 T:4114014208 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 09:48:07.037 T:3822056432 NOTICE: Creating InputStream 09:48:10.983 T:3822056432 NOTICE: Creating Demuxer 09:48:11.108 T:3822056432 NOTICE: Opening stream: 0 source: 256 09:48:11.108 T:3822056432 NOTICE: Creating video codec with codec id: 28 09:48:11.109 T:3822056432 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 09:48:11.114 T:3822056432 NOTICE: Creating video thread 09:48:11.114 T:3469505520 NOTICE: running thread: video_thread 09:48:11.114 T:3822056432 NOTICE: Opening stream: 1 source: 256 09:48:11.114 T:3822056432 NOTICE: Finding audio codec for: 86017 09:48:11.121 T:3822056432 NOTICE: Creating audio thread 09:48:11.121 T:3461116912 NOTICE: running thread: CVideoPlayerAudio::Process() 09:48:11.122 T:3822056432 NOTICE: Opening stream: 3 source: 256 09:48:11.931 T:3822056432 NOTICE: Opening stream: 0 source: 256 09:48:11.931 T:3822056432 NOTICE: Creating video codec with codec id: 28 09:48:11.933 T:3822056432 ERROR: Unable to load libamplayer.so, reason: libamplayer.so: cannot open shared object file: No such file or directory 09:48:11.933 T:3822056432 WARNING: CAMLCodec::CAMLCodec libamplayer.so not found, trying libamcodec.so instead 09:48:11.943 T:3822056432 NOTICE: Opening stream: 1 source: 256 09:48:11.943 T:3822056432 NOTICE: Closing stream player 3 09:48:11.943 T:3822056432 NOTICE: Opening stream: 3 source: 256 09:48:11.968 T:3469505520 NOTICE: CAMLCodec::OpenDecoder - using V4L2 pts format: 64Bit 09:48:11.979 T:3461116912 NOTICE: Creating audio stream (codec id: 86017, channels: 2, sample rate: 48000, no pass-through) 09:48:16.283 T:3461116912 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer 09:48:17.371 T:4114014208 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (26) (weight: 0.000) 09:48:18.696 T:3461116912 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer 09:48:31.312 T:4114014208 ERROR: Previous line repeats 3 times. 09:48:31.312 T:4114014208 NOTICE: CVideoPlayer::CloseFile() 09:48:31.312 T:4114014208 NOTICE: VideoPlayer: waiting for threads to exit 09:48:31.394 T:3822056432 NOTICE: CVideoPlayer::OnExit() 09:48:31.394 T:3822056432 NOTICE: Closing stream player 1 09:48:31.395 T:3822056432 NOTICE: Waiting for audio thread to exit 09:48:31.765 T:3469505520 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 09:48:33.495 T:3461116912 WARNING: Previous line repeats 2 times. 09:48:33.495 T:3461116912 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer 09:48:33.495 T:3461116912 NOTICE: thread end: CVideoPlayerAudio::OnExit() 09:48:33.495 T:3822056432 NOTICE: Closing audio device 09:48:33.540 T:3822056432 NOTICE: Deleting audio codec 09:48:33.540 T:3822056432 NOTICE: Closing stream player 2 09:48:33.540 T:3822056432 NOTICE: waiting for video thread to exit 09:48:33.540 T:3469505520 NOTICE: thread end: video_thread 09:48:33.540 T:3822056432 NOTICE: deleting video codec 09:48:33.578 T:3822056432 NOTICE: Closing stream player 3 09:48:34.266 T:4114014208 NOTICE: VideoPlayer: finished waiting 09:48:35.052 T:4114014208 NOTICE: CVideoPlayer::CloseFile() 09:48:35.052 T:4114014208 NOTICE: VideoPlayer: waiting for threads to exit 09:48:35.053 T:4114014208 NOTICE: VideoPlayer: finished waiting 09:48:35.053 T:4114014208 NOTICE: CVideoPlayer::CloseFile() 09:48:35.053 T:4114014208 NOTICE: VideoPlayer: waiting for threads to exit 09:48:35.053 T:4114014208 NOTICE: VideoPlayer: finished waiting 09:49:43.457 T:4114014208 NOTICE: NFS is idle. Closing the remaining connections. 09:52:57.925 T:3805279216 WARNING: CPVRRecording::CPVRRecording - unable to determine channel type. Defaulting to TV.
A proper debug log might let us see the issue more clearly
Sam
This isn’t a debug log. It seems (from what we have) that there is some buffering issue however.
Where can i find debug log?
Thanks :)!
Heres log with debug mode enabled:
https://paste.osmc.tv/uvuvahuxor
Any ides? Seems others having same problems: Sound does not work on live TV after update. TV and videos freeze
Any news?