Project

General

Profile

Bug #5647

htsp connection lost if you are playing a dvr file when enter into webui

Added by Pablo Zerón over 5 years ago. Updated over 5 years ago.

Status:
New
Priority:
Normal
Assignee:
-
Category:
HTSP
Target version:
-
Start date:
2019-06-06
Due date:
% Done:

0%

Estimated time:
Found in version:
4.3-1792~g466a01431
Affected Versions:

Description

When you are playing a video in kodi and you enter with mobile or computer to the webui, the htsp connection is lost and the video stop playing, after this you must to play the video again.

Here the log when you open kodi and start to play a dvr file.

2019-06-06 15:16:23.739 htsp: Got connection from XXX.XXX.XXX.XXX
2019-06-06 15:16:23.758 htsp: XXX.XXX.XXX.XXX: Welcomed client software: Kodi Media Center (HTSPv34)
2019-06-06 15:16:23.765 htsp: XXX.XXX.XXX.XXX [ Kodi Media Center ]: Identified as user 'r***n'
2019-06-06 15:16:23.765 htsp: XXX.XXX.XXX.XXX [ r***n | Kodi Media Center ]: Privileges updated
2019-06-06 15:16:23.772 htsp: Opening file /mnt/sda/Grabaciones/La fiebre del oro_ aguas bravas/La fiebre del oro_ aguas bravas (Descubrimiento de oro)_Discovery Channel_2019-06-02_22-00_S02E10.ts -- OK

Here when you enter to the webui:

2019-06-06 15:16:28.776 htsp: XXX.XXX.XXX.XXX [ r***n | Kodi Media Center ]: Disconnected
2019-06-06 15:16:28.776 htsp: Closed opened file /mnt/sda/Grabaciones/La fiebre del oro_ aguas bravas/La fiebre del oro_ aguas bravas (Descubrimiento de oro)_Discovery Channel_2019-06-02_22-00_S02E10.ts
2019-06-06 15:16:28.776 subscription: 001E: "HTSP" unsubscribing, hostname="XXX.XXX.XXX.XXX", username="r***n", client="Kodi Media Center"
2019-06-06 15:16:43.791 htsp: Got connection from XXX.XXX.XXX.XXX
2019-06-06 15:16:43.801 htsp: XXX.XXX.XXX.XXX: Welcomed client software: Kodi Media Center (HTSPv34)
2019-06-06 15:16:43.807 htsp: XXX.XXX.XXX.XXX [ Kodi Media Center ]: Identified as user 'r***n'
2019-06-06 15:16:43.807 htsp: XXX.XXX.XXX.XXX [ r***n | Kodi Media Center ]: Privileges updated
2019-06-06 15:16:44.128 dvr: "La fiebre del oro: aguas bravas" on "Discovery Channel": Updated ()

History

#1

Updated by Flole Systems over 5 years ago

Provide debug logs of Kodi's HTSP Client.

#2

Updated by Pablo Zerón over 5 years ago

Sorry for the delay, here you have the log:

2019-06-09 15:13:24.278 T:1876 NOTICE: PVR Manager: Starting
2019-06-09 15:13:25.588 T:6080 NOTICE: PVR Manager: Started
2019-06-09 15:13:25.597 T:6080 ERROR: PVR::CPVREpg::UpdateFromScraper: Channel not found, can't update
2019-06-09 15:13:25.702 T:6500 ERROR: Previous line repeats 260 times.
2019-06-09 15:13:25.704 T:6500 NOTICE: EPG thread started
2019-06-09 15:13:31.888 T:6500 ERROR: XFILE::CDirectory::GetDirectory - Error getting
2019-06-09 15:13:36.591 T:6440 NOTICE: VideoPlayer::OpenFile: pvr://recordings/tv/active/Ingenieria abandonada/Ingenier%c3%ada%20abandonada s02e11%20Episodio%2011, TV%20(Discovery%20Channel), 20190605_205000, 434555353.pvr
2019-06-09 15:13:36.654 T:8788 NOTICE: Creating InputStream
2019-06-09 15:13:36.725 T:8788 NOTICE: Creating Demuxer
2019-06-09 15:13:37.345 T:8788 NOTICE: Opening stream: 0 source: 256
2019-06-09 15:13:37.346 T:8788 WARNING: CVideoPlayer::OpenStream - Unsupported stream 0. Stream disabled.
2019-06-09 15:13:37.347 T:8788 NOTICE: Opening stream: 1 source: 256
2019-06-09 15:13:37.348 T:8788 NOTICE: Finding audio codec for: 86016
2019-06-09 15:13:37.372 T:8788 NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder mp2
2019-06-09 15:13:37.373 T:8788 NOTICE: Creating audio thread
2019-06-09 15:13:37.374 T:5892 NOTICE: running thread: CVideoPlayerAudio::Process()
2019-06-09 15:13:38.124 T:8788 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
2019-06-09 15:13:39.212 T:8788 WARNING: Previous line repeats 1 times.
2019-06-09 15:13:39.213 T:8788 NOTICE: Opening stream: 0 source: 256
2019-06-09 15:13:39.214 T:8788 NOTICE: Creating video codec with codec id: 2
2019-06-09 15:13:39.215 T:8788 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video
2019-06-09 15:13:39.222 T:8788 NOTICE: Creating video thread
2019-06-09 15:13:39.223 T:6296 NOTICE: running thread: video_thread
2019-06-09 15:13:39.232 T:8788 NOTICE: Opening stream: 1 source: 256
2019-06-09 15:13:39.267 T:5892 NOTICE: Creating audio stream (codec id: 86016, channels: 2, sample rate: 48000, no pass-through)
2019-06-09 15:13:39.467 T:6440 NOTICE: CWinRenderer::SelectRenderMethod: rendering method forced to DXVA processor.
2019-06-09 15:13:39.481 T:6440 NOTICE: DXVA::CProcessorHD::InitProcessor: supported deinterlace methods: blend:yes, bob:yes, adaptive:yes, mocomp:no.
2019-06-09 15:13:39.517 T:6440 WARNING: CWinRenderer::UpdateVideoFilter: chosen scaling method 1 is not supported by renderer
2019-06-09 15:13:40.032 T:6296 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
2019-06-09 15:13:40.861 T:6296 WARNING: Previous line repeats 6 times.
2019-06-09 15:13:40.862 T:6296 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000
2019-06-09 15:13:50.905 T:6296 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer

Here after 7 minutes watching the video, I enter to the next recordings tab in webui, then:

2019-06-09 15:21:12.390 T:8788 WARNING: Previous line repeats 16 times.
2019-06-09 15:21:12.390 T:8788 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileRead failed: No response received
2019-06-09 15:21:12.391 T:8788 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2019-06-09 15:21:15.034 T:5892 NOTICE: CVideoPlayerAudio::Process - stream stalled
2019-06-09 15:21:17.875 T:3568 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileOpen failed: No response received
2019-06-09 15:21:17.876 T:3568 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs failed to re-open file
2019-06-09 15:21:27.879 T:3568 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to write (Invalid argument)
2019-06-09 15:21:27.880 T:3568 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to transmit
2019-06-09 15:21:27.881 T:2308 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - message without a method
2019-06-09 15:21:27.882 T:8788 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs fileRead failed
2019-06-09 15:21:27.884 T:8788 NOTICE: CVideoPlayer::OnExit()
2019-06-09 15:21:27.885 T:8788 NOTICE: VideoPlayer: eof, waiting for queues to empty
2019-06-09 15:21:27.886 T:8788 NOTICE: Closing stream player 1
2019-06-09 15:21:27.886 T:8788 NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
2019-06-09 15:21:27.887 T:5892 NOTICE: CVideoPlayerAudio::Process - stream stalled
2019-06-09 15:21:27.888 T:8788 NOTICE: Waiting for audio thread to exit
2019-06-09 15:21:27.889 T:5892 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2019-06-09 15:21:27.889 T:5892 NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-06-09 15:21:27.890 T:8788 NOTICE: Closing audio device
2019-06-09 15:21:27.894 T:1884 WARNING: ActiveAE - large audio sync error: -12481.048733
2019-06-09 15:21:27.896 T:8788 NOTICE: Deleting audio codec
2019-06-09 15:21:27.897 T:8788 NOTICE: Closing stream player 2
2019-06-09 15:21:27.898 T:8788 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
2019-06-09 15:21:27.899 T:8788 NOTICE: waiting for video thread to exit
2019-06-09 15:21:27.899 T:6296 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2019-06-09 15:21:27.900 T:6296 NOTICE: thread end: video_thread
2019-06-09 15:21:27.901 T:8788 NOTICE: deleting video codec
2019-06-09 15:21:34.165 T:6440 NOTICE: CVideoPlayer::CloseFile()
2019-06-09 15:21:34.166 T:6440 NOTICE: DXVA::CDecoder::Close: closing decoder.
2019-06-09 15:21:34.167 T:6440 NOTICE: DXVA: closing decoder context.
2019-06-09 15:21:34.171 T:6440 NOTICE: VideoPlayer: waiting for threads to exit
2019-06-09 15:21:34.171 T:6440 NOTICE: VideoPlayer: finished waiting
2019-06-09 15:21:34.172 T:6440 NOTICE: CVideoPlayer::CloseFile()
2019-06-09 15:21:34.172 T:6440 NOTICE: VideoPlayer: waiting for threads to exit
2019-06-09 15:21:34.173 T:6440 NOTICE: VideoPlayer: finished waiting

Thanks.

Also available in: Atom PDF