search

kodi-pvr / pvr.iptvsimple

IPTV Simple client for Kodi PVR
GNU General Public License v2.0
782 stars 372 forks source link

crash after drag the progress bar in playback a rtsp uri #655

Closed VergilGao closed 1 year ago

VergilGao commented 2 years ago

i set the request string as &playseek={utc:YmdHMS}-{utcend:YmdHMS} if i drag the progress bar, the playback crashed i use tcpdump to see what happened , then i got:

RTSP/1.0 457 Invalid Range

when i start, kodi send DESCRIBE rtsp://********&playseek=20221022101000-20221022102000 got 200 ok

i drage the progress bar kodi send PAUSE rtsp://******* got 200 ok then kodi send PLAY rtsp://******* with a parameter Range: npt=40938.634-\r\n got 457 Invalid Range

VergilGao commented 2 years ago

it seems the range value is wrong i will find out the reason

VergilGao commented 2 years ago

here is the full log:(use ** instead of my account info)

2022-11-08 16:48:46.104 T:22024   DEBUG <general>: Loading settings for pvr://guide/0034/2022-11-07 19:46:00.epg
2022-11-08 16:48:46.105 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: inputstream.ffmpegdirect: SetVideoResolution()
2022-11-08 16:48:46.105 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: inputstream.ffmpegdirect: OpenStream() - Num Props: 0
2022-11-08 16:48:46.105 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: Stream mimetype: application/smil
2022-11-08 16:48:46.106 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: inputstream.ffmpegdirect: OpenStream()
2022-11-08 16:48:46.106 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpegdirect::FFmpegStream::OpenWithFFmpeg - IO handled by FFmpeg's AVFormat
2022-11-08 16:48:46.117 T:30520   DEBUG <general>: CPlayerGUIInfo::InitCurrentItem(pvr://guide/0034/2022-11-07 19:46:00.epg)
2022-11-08 16:48:46.197 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]: [rtsp] Status 302: Redirecting to rtsp://10.255.2.19:554/PLTV/88888888/224/3221225639/10000100000000060000000000002452_0.smil?rrsip=10.128.20.53&zoneoffset=480&icpid=-1&accounttype=1&limitflux=-1&limitdur=-1&tenantId=8601&accountinfo=******
2022-11-08 16:48:46.217 T:30520   DEBUG <general>: ------ Window Init (DialogBusy.xml) ------
2022-11-08 16:48:46.344 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: ffmpegdirect::FFmpegStream::Open - avformat_find_stream_info starting
2022-11-08 16:48:46.874 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: ffmpegdirect::FFmpegStream::Open - av_find_stream_info finished
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]: Input #0, rtsp, from 'rtsp://10.128.20.53/PLTV/88888888/224/3221225639/10000100000000060000000000002452_0.smil?rrsip=10.128.20.53&zoneoffset=480&icpid=-1&accounttype=1&limitflux=-1&limitdur=-1&tenantId=8601&accountinfo=******&playseek=20221108034600-20221108044600':
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Metadata:
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:     title           : vod
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Duration: 01:00:00.00, start: 25918.114289, bitrate: N/A
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Program 1 
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:     Metadata:
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:       service_name    : CCTV9-HD-I
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:       service_provider: 
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Stream #0:0: Video: h264 (High), yuv420p(top first), 1920x1080 [SAR 1:1 DAR 16:9], 25 fps, 50 tbr, 90k tbn, 50 tbc
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Stream #0:1: Audio: aac (LC), 48000 Hz, stereo, fltp, 127 kb/s
2022-11-08 16:48:46.875 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: CDVDDemuxFFmpeg::AddStream ID: 0
2022-11-08 16:48:46.875 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: CDVDDemuxFFmpeg::AddStream ID: 1
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]: Input #0, rtsp, from 'rtsp://10.128.20.53/PLTV/88888888/224/3221225639/10000100000000060000000000002452_0.smil?rrsip=10.128.20.53&zoneoffset=480&icpid=-1&accounttype=1&limitflux=-1&limitdur=-1&tenantId=8601&accountinfo=******&playseek=20221108034600-20221108044600':
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Metadata:
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:     title           : vod
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Duration: 01:00:00.00, start: 25918.114289, bitrate: N/A
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Program 1 
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:     Metadata:
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:       service_name    : CCTV9-HD-I
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:       service_provider: 
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Stream #0:0: Video: h264 (High), yuv420p(top first), 1920x1080 [SAR 1:1 DAR 16:9], 25 fps, 50 tbr, 90k tbn, 50 tbc
2022-11-08 16:48:46.875 T:13868    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]:   Stream #0:1: Audio: aac (LC), 48000 Hz, stereo, fltp, 127 kb/s
2022-11-08 16:48:46.875 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: GetCapabilities()
2022-11-08 16:48:46.881 T:13868    INFO <general>: Skipped 1 duplicate messages..
2022-11-08 16:48:46.881 T:13868    INFO <general>: Creating Demuxer
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: GetStreamIds()
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: Skipped 1 duplicate messages..
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: GetStream(0)
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: GetStream(1)
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: CDVDDemuxClient::RequestStream(): added/updated stream 0 with codec_id 27
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: CDVDDemuxClient::RequestStream(): added/updated stream 1 with codec_id 86018
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: ffmpegdirect::FFmpegStream::LengthStream: 3600000000
2022-11-08 16:48:46.881 T:13868    INFO <general>: Opening stream: 0 source: 256
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: GetStream(0)
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: CDVDDemuxClient::RequestStream(): added/updated stream 0 with codec_id 27
2022-11-08 16:48:46.881 T:13868    INFO <general>: Creating video codec with codec id: 27
2022-11-08 16:48:46.881 T:13868    INFO <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2022-11-08 16:48:46.881 T:13868   DEBUG <general>: CVideoPlayerVideo::OpenStream - open stream with codec id: 27
2022-11-08 16:48:46.881 T:13868    INFO <general>: Creating video thread
2022-11-08 16:48:46.881 T:22268   DEBUG <general>: Thread VideoPlayerVideo start, auto delete: false
2022-11-08 16:48:46.881 T:22268    INFO <general>: running thread: video_thread
2022-11-08 16:48:46.881 T:22268   DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
2022-11-08 16:48:46.882 T:13868   DEBUG <general>: PVR::CPVREdl::GetCuts: Reading EDL for EPG tag: 特别呈现(311)
2022-11-08 16:48:46.882 T:13868    INFO <general>: Opening stream: 1 source: 256
2022-11-08 16:48:46.882 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: GetStream(1)
2022-11-08 16:48:46.882 T:13868   DEBUG <general>: CDVDDemuxClient::RequestStream(): added/updated stream 1 with codec_id 86018
2022-11-08 16:48:46.882 T:13868    INFO <general>: Finding audio codec for: 86018
2022-11-08 16:48:46.883 T:13868    INFO <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2022-11-08 16:48:46.883 T:13868    INFO <general>: Creating audio thread
2022-11-08 16:48:46.883 T:3384    DEBUG <general>: Thread VideoPlayerAudio start, auto delete: false
2022-11-08 16:48:46.883 T:3384     INFO <general>: running thread: CVideoPlayerAudio::Process()
2022-11-08 16:48:46.883 T:3384    DEBUG <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0
2022-11-08 16:48:46.883 T:13868   DEBUG <general>: CVideoPlayer::SetCaching - caching state 2
2022-11-08 16:48:46.883 T:13868   DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2022-11-08 16:48:46.883 T:13868   DEBUG <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2022-11-08 16:48:46.883 T:13868   DEBUG <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2022-11-08 16:48:46.883 T:22024   DEBUG <general>: CApplication::OnAVChange: CApplication::OnAVChange
2022-11-08 16:48:46.883 T:13868   DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2022-11-08 16:48:46.883 T:13868   DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2022-11-08 16:48:46.884 T:22268   DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2022-11-08 16:48:46.884 T:22268   DEBUG <general>: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2022-11-08 16:48:46.884 T:22268   DEBUG <general>: DXVA: open decoder.
2022-11-08 16:48:46.884 T:22268    INFO <general>: DXVA::CContext::CreateContext: creating discrete d3d11va device for decoding.
2022-11-08 16:48:46.891 T:22268   DEBUG <general>: HookOpenAdapter10_2: hook into pOpenData->pAdapterFuncs->pfnCreateDevice
2022-11-08 16:48:46.894 T:3384    DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2022-11-08 16:48:46.894 T:3384     INFO <general>: Skipped 1 duplicate messages..
2022-11-08 16:48:46.894 T:3384     INFO <general>: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
2022-11-08 16:48:46.894 T:3384    DEBUG <general>: CVideoPlayerAudio:: synctype set to 0: clock feedback
2022-11-08 16:48:46.896 T:24312    INFO <general>: CActiveAESink::OpenSink - initialize sink
2022-11-08 16:48:46.896 T:24312   DEBUG <general>: CAESinkDirectSound::Deinitialize: Cleaning up
2022-11-08 16:48:46.899 T:24312   DEBUG <general>: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
2022-11-08 16:48:46.903 T:24312   DEBUG <general>: CAESinkDirectSound::Initialize: Using Window handle: 0x4c0d58
2022-11-08 16:48:46.907 T:24312   DEBUG <general>: CAESinkDirectSound::Initialize: secondary buffer created
2022-11-08 16:48:46.907 T:24312   DEBUG <general>: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
2022-11-08 16:48:46.907 T:24312   DEBUG <general>:   Audio Device    : Headphone (Realtek(R) Audio)
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Sample Rate     : 48000
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Sample Format   : AE_FMT_FLOAT
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Bits Per Sample : 32
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Valid Bits/Samp : 32
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Channel Count   : 2
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Block Align     : 8
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Avg. Bytes Sec  : 384000
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Samples/Block   : 32
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Format cBSize   : 22
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Channel Layout  : FL, FR
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Channel Mask    : 3
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Frames          : 720
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Frame Size      : 8
2022-11-08 16:48:46.908 T:24312   DEBUG <general>: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Output Device : default
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Sample Rate   : 48000
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Sample Format : AE_FMT_FLOAT
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Channel Count : 2
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Channel Layout: FL, FR
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Frames        : 720
2022-11-08 16:48:46.908 T:24312   DEBUG <general>:   Frame Size    : 8
2022-11-08 16:48:46.908 T:12508   DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2022-11-08 16:48:46.922 T:13868   DEBUG <general>: Skipped 1 duplicate messages..
2022-11-08 16:48:46.922 T:13868   DEBUG <general>: CVideoPlayer::HandleMessages - player started 1
2022-11-08 16:48:46.922 T:22024   DEBUG <general>: CApplication::OnAVChange: CApplication::OnAVChange
2022-11-08 16:48:46.923 T:24312   DEBUG <general>: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
2022-11-08 16:48:46.930 T:22268   DEBUG <general>: HookCreateDevice: hook into pCreateData->pDeviceFuncs->pfnCreateResource
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'MPEG1/2 variable-length decoder'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'MPEG2 variable-length decoder'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'MPEG-1 variable-length decoder'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'VC-1 variable-length decoder 2010'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'VC-1 variable-length decoder'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 32FCFE3F-de46-4a49-861b-ac71110649d5
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'H.264 stereo high profile, mbs flag set'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'H.264 stereo high profile'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'H.264 variable-length decoder, no film grain technology'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'HEVC variable-length decoder, main'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'HEVC variable-length decoder, main10'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 20BB8B0A-97aa-4571-8e99-64e60606c1a6
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 15DF9B21-06c4-47f1-841e-a67c97d7f312
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports EFD64D74-c9e8-41d7-a5e9-e9b0e39fa319
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports ED418A9F-010d-4eda-9ae3-9a65358d8d2e
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 9947EC6F-689b-11dc-a320-0019dbbc4184
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 33FCFE41-de46-4a49-861b-ac71110649d5
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'VP9 variable-length decoder, Profile 0'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 'VP9 variable-length decoder, 10bit, profile 2'
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports DDA19DC7-93b5-49f5-a9b3-2bda28a2ce6e
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports B8BE4CCB-cf53-46ba-8d59-d6b8a6da5d2a
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 6AFFD11E-1d96-42b1-a215-93a31f09a53d
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 914C84A3-4078-4fa9-984c-e2f262cb5c9c
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: supports 8A1A1031-29bc-46d0-a007-e9b092ca6767
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: trying 'H.264 variable-length decoder, no film grain technology'.
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: config 0: bitstream type 1.
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: config 1: bitstream type 2.
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: config 2: bitstream type 2, encrypted.
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: selected output format: 103.
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: source requires 3 references.
2022-11-08 16:48:46.932 T:22268    INFO <general>: DXVA::CDecoder::Open: Total video memory available is 11849 MB (dedicated = 3991 MB, shared = 7858 MB)
2022-11-08 16:48:46.932 T:22268   DEBUG <general>: DXVA: allocating 11 surfaces with format 103.
2022-11-08 16:48:46.937 T:22268   DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-d3d11va
2022-11-08 16:48:46.958 T:22268   DEBUG <general>: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2022-11-08 16:48:46.963 T:22268   DEBUG <general>: Skipped 2 duplicate messages..
2022-11-08 16:48:46.963 T:22268   DEBUG <general>: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 50.00.
2022-11-08 16:48:46.969 T:30520   DEBUG <general>: CRenderManager::DeleteRenderer - deleting renderer
2022-11-08 16:48:46.969 T:30520   DEBUG <general>: CWinRenderer::SelectRenderer: requested render method: 0
2022-11-08 16:48:46.969 T:30520   DEBUG <general>: CWinRenderer::SelectRenderer: selected render method: DXVA
2022-11-08 16:48:46.969 T:30520   DEBUG <general>: CWIN32Util::GetWindowsHDRStatus: Display is not HDR capable or cannot be detected
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: initing video enumerator with params: 1920x1920.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: video processor has 1 rate conversion.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: video processor has 0x1ef feature caps.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: video processor has 0x1d device caps.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: video processor has 0x4 input format caps.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: video processor has 5 max input streams.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: video processor has 5 max stream states.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: selected video processor index: 0.
2022-11-08 16:48:46.970 T:30520    INFO <general>: DXVA::CProcessorHD::InitProcessor: supported deinterlace methods: blend:yes, bob:yes, adaptive:yes, mocomp:no.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: selected video processor allows 1 future frames and 2 past frames.
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: filter 0 has following params - max: 101, min: -101, default: 0
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: filter 1 has following params - max: 160, min: 40, default: 100
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: filter 2 has following params - max: 180, min: -180, default: 0
2022-11-08 16:48:46.970 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: filter 3 has following params - max: 187, min: 13, default: 100
2022-11-08 16:48:46.971 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: HLG color space conversion is NOT supported.
2022-11-08 16:48:46.971 T:30520   DEBUG <general>: DXVA::CProcessorHD::InitProcessor: HDR10 RGB limited range output is NOT supported.
2022-11-08 16:48:46.971 T:30520   DEBUG <general>: DXVA::CProcessorHD::OpenProcessor: creating processor.
2022-11-08 16:48:46.972 T:30520   DEBUG <general>: CRenderManager::Configure - 6
2022-11-08 16:48:46.972 T:30520 WARNING <general>: CRendererHQ::CheckVideoParameters: chosen scaling method 1 is not supported by renderer
2022-11-08 16:48:46.972 T:30520   DEBUG <general>: CRendererBase::CreateIntermediateTarget: intermediate target format 87.
2022-11-08 16:48:46.972 T:30520   DEBUG <general>: CWinShader::LoadEffect: loading shader special://xbmc/system/shaders/output_d3d.fx
2022-11-08 16:48:46.975 T:13868   DEBUG <general>: CVideoPlayer::HandleMessages - player started 2
2022-11-08 16:48:46.975 T:22024   DEBUG <general>: CApplication::OnAVChange: CApplication::OnAVChange
2022-11-08 16:48:46.975 T:13868   DEBUG <general>: VideoPlayer::Sync - Audio - pts: 298666.000000, cache: 319999.963045, totalcache: 780000.030994
2022-11-08 16:48:46.975 T:13868   DEBUG <general>: VideoPlayer::Sync - Video - pts: 946855.000000, cache: 50000.000000, totalcache: 100000.000000
2022-11-08 16:48:46.975 T:13868   DEBUG <general>: CVideoPlayer::SetCaching - caching state 0
2022-11-08 16:48:46.975 T:13868   DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2022-11-08 16:48:46.975 T:22268   DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(298666.036955)
2022-11-08 16:48:46.975 T:22268   DEBUG <general>: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2022-11-08 16:48:46.975 T:3384    DEBUG <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(298666.036955), level: 4, cache: 470017.163045
2022-11-08 16:48:46.975 T:22024   DEBUG <general>: CApplication::OnAVStarted: CApplication::OnAVStarted
2022-11-08 16:48:46.975 T:3384    DEBUG <general>: CDVDAudio::Resume - resume audio stream
2022-11-08 16:48:46.975 T:12508   DEBUG <general>: ActiveAE - start sync of audio stream
2022-11-08 16:48:46.976 T:22268   DEBUG <general>: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2022-11-08 16:48:46.978 T:22268   DEBUG <general>: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
2022-11-08 16:48:46.987 T:30520   DEBUG <general>: Skipped 1 duplicate messages..
2022-11-08 16:48:46.987 T:30520   DEBUG <general>: Activating window ID: 12005
2022-11-08 16:48:46.987 T:30520   DEBUG <general>: ------ Window Deinit (MyPVRGuide.xml) ------
2022-11-08 16:48:46.987 T:25208   DEBUG <general>: Thread epg-grid-refresh-timeline-items 25208 terminating
2022-11-08 16:48:46.987 T:30520   DEBUG <general>: FreeVisualisation() done
2022-11-08 16:48:46.987 T:30520   DEBUG <general>: ------ Window Init (VideoFullScreen.xml) ------
2022-11-08 16:48:46.987 T:30520    INFO <general>: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
2022-11-08 16:48:46.991 T:22024   DEBUG <general>: CApplication::OnAVChange: CApplication::OnAVChange
2022-11-08 16:48:47.017 T:30520   DEBUG <general>: ------ Window Deinit (DialogBusy.xml) ------
2022-11-08 16:48:47.083 T:12508   DEBUG <general>: ActiveAE::SyncStream - average error of -454.923472, start adjusting
2022-11-08 16:48:47.100 T:30520   DEBUG <general>: ------ Window Init (VideoOSD.xml) ------
2022-11-08 16:48:47.100 T:30520    INFO <general>: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY
2022-11-08 16:48:47.105 T:30520   DEBUG <general>: ------ Window Init (DialogSeekBar.xml) ------
2022-11-08 16:48:47.106 T:30520   DEBUG <general>: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
2022-11-08 16:48:47.106 T:1756    DEBUG <general>: Thread AlarmClock start, auto delete: false
2022-11-08 16:48:47.106 T:30520   DEBUG <general>: started alarm with name: seekbartimer
2022-11-08 16:48:47.289 T:12508   DEBUG <general>: ActiveAE::SyncStream - average error -22.175155 below threshold of 30.000000
2022-11-08 16:48:47.501 T:22268 WARNING <general>: CVideoPlayerVideo::OutputPicture - timeout waiting for buffer
2022-11-08 16:48:47.853 T:22268    INFO <general>: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000
2022-11-08 16:48:48.303 T:12508   DEBUG <general>: ActiveAE::SyncStream - average error 105.933212 above threshold of 100.000000
2022-11-08 16:48:48.303 T:12508   DEBUG <general>: ActiveAE::SyncStream - average error 15.933212 below threshold of 30.000000
2022-11-08 16:48:49.350 T:3384    DEBUG <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:15251.525234, adjusted:15251.525234
2022-11-08 16:48:50.095 T:3384     INFO <general>: CVideoPlayerAudio::Process - stream stalled
2022-11-08 16:48:50.098 T:13868   DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2022-11-08 16:48:50.098 T:13868   DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2022-11-08 16:48:50.098 T:3384    DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2022-11-08 16:48:57.148 T:13868   DEBUG <general>: CVideoPlayer::SetCaching - caching state 2
2022-11-08 16:48:57.148 T:13868   DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2022-11-08 16:48:57.148 T:13868   DEBUG <general>: CVideoPlayer::SetCaching - caching state 3
2022-11-08 16:48:57.148 T:3384    DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2022-11-08 16:48:57.148 T:13868   DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2022-11-08 16:48:57.148 T:3384    DEBUG <general>: CDVDAudio::Resume - resume audio stream
2022-11-08 16:48:57.148 T:12508   DEBUG <general>: ActiveAE - start sync of audio stream
2022-11-08 16:48:57.158 T:13868   DEBUG <general>: CVideoPlayer::SetCaching - caching state 0
2022-11-08 16:48:57.158 T:13868   DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2022-11-08 16:48:57.257 T:12508   DEBUG <general>: ActiveAE::SyncStream - average error of 110.735706, start adjusting
2022-11-08 16:48:57.336 T:12508   DEBUG <general>: ActiveAE::SyncStream - average error 20.735706 below threshold of 30.000000
2022-11-08 16:48:58.369 T:3384    DEBUG <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:14701.393035, adjusted:14701.393035
2022-11-08 16:48:58.517 T:30520   DEBUG <general>: CInputManager::ProcessMouse: trying mouse action leftclick
2022-11-08 16:48:58.523 T:13868   DEBUG <general>: CVideoPlayer::SetCaching - caching state 2
2022-11-08 16:48:58.523 T:13868   DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2022-11-08 16:48:58.523 T:13868   DEBUG <general>: demuxer seek to: 499218.000000
2022-11-08 16:48:58.543 T:3384    DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2022-11-08 16:48:58.575 T:13868   ERROR <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]: [rtsp] method PLAY failed: 457 Invalid Range
2022-11-08 16:48:58.575 T:13868   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: ffmpegdirect::FFmpegStream::SeekTime - seek ended up on time 12746
2022-11-08 16:48:58.575 T:13868   DEBUG <general>: VideoPlayer: seek failed or hit end of stream
2022-11-08 16:48:58.575 T:13868   DEBUG <general>: CVideoPlayer::FlushBuffers - flushing buffers
VergilGao commented 2 years ago

i think the key line is 2022-11-08 16:48:46.875 T:13868 INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]: Duration: 01:00:00.00, start: 25918.114289, bitrate: N/A and 2022-11-08 16:48:58.575 T:13868 ERROR <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[253D317F]: [rtsp] method PLAY failed: 457 Invalid Range the start value should be 0, not 25918.114289

and i cant see any debug log of addon inputstream.rtmp

phunkyfish commented 2 years ago

I don’t see where the value 25918.114289 comes from I’m afraid.

VergilGao commented 1 year ago

I don’t see where the value 25918.114289 comes from I’m afraid.

hello, i found the value was send to here https://github.com/xbmc/inputstream.rtmp/blob/9ab6dfcc328de7abf136341b408294df96998bf6/src/RTMPStream.cpp#L156 but i dont know who send this value.

VergilGao commented 1 year ago

i know what happened here! i start a stream, it logs

2023-03-19 19:39:36.248 T:18072   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: ffmpegdirect::FFmpegStream::Open - av_find_stream_info finished
2023-03-19 19:39:36.248 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]: Input #0, rtsp, from 'rtsp://mystream.url&playseek=20230319190000-20230319193200':
2023-03-19 19:39:36.248 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Metadata:
2023-03-19 19:39:36.248 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:     title           : vod
2023-03-19 19:39:36.248 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Duration: 00:32:00.00, start: 26328.772378, bitrate: N/A
2023-03-19 19:39:36.248 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Program 1 
2023-03-19 19:39:36.248 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:     Metadata:
2023-03-19 19:39:36.248 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:       service_name    : CCTV1-HD-I
2023-03-19 19:39:36.248 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:       service_provider: 
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(progressive), 1920x1080 [SAR 1:1 DAR 16:9], 25 fps, 100 tbr, 90k tbn, 50 tbc
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Stream #0:1: Audio: aac (LC), 48000 Hz, stereo, fltp, 131 kb/s
2023-03-19 19:39:36.249 T:18072   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: CDVDDemuxFFmpeg::AddStream ID: 0
2023-03-19 19:39:36.249 T:18072   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: CDVDDemuxFFmpeg::AddStream ID: 1
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]: Input #0, rtsp, from 'rtsp://mystream.url&playseek=20230319190000-20230319193200':
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Metadata:
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:     title           : vod
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Duration: 00:32:00.00, start: 26328.772378, bitrate: N/A
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Program 1 
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:     Metadata:
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:       service_name    : CCTV1-HD-I
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:       service_provider: 
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(progressive), 1920x1080 [SAR 1:1 DAR 16:9], 25 fps, 100 tbr, 90k tbn, 50 tbc
2023-03-19 19:39:36.249 T:18072    INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]:   Stream #0:1: Audio: aac (LC), 48000 Hz, stereo, fltp, 131 kb/s
2023-03-19 19:39:36.249 T:18072   DEBUG <general>: AddOnLog: inputstream.ffmpegdirect: GetCapabilities()

this line: 2023-03-19 19:39:36.248 T:18072 INFO <general>: AddOnLog: inputstream.ffmpegdirect: ffmpeg[EA45BCCB]: Duration: 00:32:00.00, start: 26328.772378, bitrate: N/A the start is 26328 then i drag the progress to 00:17 then kodi send a request with:

Range: npt=26345.772-\r\n

26345 - 26328 = 17

then, i try again, drag the progress to 00:50

Range: npt=26378.772-\r\n

26378 - 26328 = 50 and the extra 0.772 is in the start 26328.772378 so i guess, when i drag a progress bar, someone add the progress value and a number called start then send the sum.

then i restart kodi i found the start is still 26328.772378.

then i try another stream, the start is 17161.329422 now.

ok, i check the source code the found the parameter is output here https://github.com/xbmc/inputstream.ffmpegdirect/blob/278312774bb9d7f8936c7463a1d363e9b88a3221/src/stream/FFmpegStream.cpp#L762

the start is part of the protocol，this is a wrong way, i need to find out who add the start and the progress value, and i know the start is FFmpegStream::m_pFormatContext->start_time got it: https://github.com/xbmc/inputstream.ffmpegdirect/blob/278312774bb9d7f8936c7463a1d363e9b88a3221/src/stream/FFmpegStream.cpp#LL1483C46-L1483C46

VergilGao commented 1 year ago

it fixed by add #KODIPROP:inputstream=inputstream.rtmp to my m3u file. see https://github.com/xbmc/inputstream.ffmpegdirect/issues/232#issuecomment-1486946037