xbmc / inputstream.adaptive

kodi inputstream addon for several manifest types
Other
451 stars 241 forks source link

Some MPD stop playback immediately after parser refactor #1232

Closed tmm1 closed 1 year ago

tmm1 commented 1 year ago

Bug report

Describe the bug

After #1067 some MPD files will fail to play.

672c73cfdebc286cc2944d579c1ead31b473897a - fails 454eaf0a10da33f7bec22f32fd62c8908721093e - works

Expected Behavior

Normal playback. Here is the captured log with 454eaf0a10da33f7bec22f32fd62c8908721093e build:

I will post the log in next comment due to github size limit

Actual Behavior

broken log ``` 2023-04-22 19:05:18.513 T:27887843 debug : Keyboard: scancode: 0x24, sym: 0x0d, unicode: 0x0d, modifier: 0x0 2023-04-22 19:05:18.678 T:27887843 debug : Keyboard: scancode: 0x24, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-04-22 19:05:18.679 T:27887843 debug : HandleKey: return (0xf00d) pressed, window 10025, action is Select 2023-04-22 19:05:18.679 T:27887843 debug : OnPlayMedia /Volumes/tmm1-ssd/kodi-videos/mint.m3u 2023-04-22 19:05:18.680 T:27887843 debug : easy_acquire - Created session to https://dice-live-us.akamaized.net 2023-04-22 19:05:19.158 T:27887843 debug : CCurlFile::GetMimeType - -> application/dash+xml 2023-04-22 19:05:19.161 T:27887843 debug : CPlayerCoreFactory::GetPlayers(https://dice-live-us.akamaized.net/dash/live/2001830/237410-240341/manifest.mpd?hdnea=st=1682215506~exp=1682215546~acl=/dash/live/2001830/237410-240341/*~id=d5f16201-5e51-4fec-aa8a-3262de9c99af~data=d5f16201-5e51-4fec-aa8a-3262de9c99af~hmac=6688415e3c64cebb4597c15608233c0ec7515efd4c1efe46483f00346ebb1e82&opId=322&cc=US&ua=TW96aWxsYS81LjAgKE1hY2ludG9zaDsgSW50ZWwgTWFjIE9TIFggMTBfMTVfNykgQXBwbGVXZWJLaXQvNTM3LjM2IChLSFRNTCwgbGlrZSBHZWNrbykgQ2hyb21lLzExMC4wLjAuMCBTYWZhcmkvNTM3LjM2&sd=US-CA) 2023-04-22 19:05:19.161 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2023-04-22 19:05:19.161 T:27887843 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2023-04-22 19:05:19.161 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: matches rule: streams 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: mp2 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2023-04-22 19:05:19.162 T:27887843 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2023-04-22 19:05:19.163 T:27887843 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2023-04-22 19:05:19.163 T:27887843 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2023-04-22 19:05:19.163 T:27887843 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2023-04-22 19:05:19.163 T:27887843 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2023-04-22 19:05:19.163 T:27887843 debug : Radio UECP (RDS) Processor - new CDVDRadioRDSData 2023-04-22 19:05:19.163 T:27887843 debug : Audio ID3 tag processor - new CVideoPlayerAudioID3 2023-04-22 19:05:19.163 T:27887843 info : VideoPlayer::OpenFile: /Volumes/tmm1-ssd/kodi-videos/mint.m3u 2023-04-22 19:05:19.164 T:27954634 debug : Thread VideoPlayer start, auto delete: false 2023-04-22 19:05:19.164 T:27887843 debug : OnPlayBackStarted: CApplication::OnPlayBackStarted 2023-04-22 19:05:19.164 T:27954635 debug : Thread BackgroundLoader start, auto delete: false 2023-04-22 19:05:19.164 T:27887843 debug : [threads] setting priority is not supported on this platform 2023-04-22 19:05:19.169 T:27887843 debug : CVideoGUIInfo::InitCurrentItem(/Volumes/tmm1-ssd/kodi-videos/mint.m3u) 2023-04-22 19:05:19.171 T:27954635 debug : Thread BackgroundLoader 0x70000bf7e000 terminating 2023-04-22 19:05:19.175 T:27887843 debug : CPlayerGUIInfo::InitCurrentItem(/Volumes/tmm1-ssd/kodi-videos/mint.m3u) 2023-04-22 19:05:19.275 T:27887843 debug : ------ Window Init (DialogBusy.xml) ------ 2023-04-22 19:05:19.275 T:27887843 debug : Window DialogBusy.xml was already loaded 2023-04-22 19:05:19.276 T:27887843 debug : Alloc resources: 0.00 ms 2023-04-22 19:05:19.355 T:27954634 debug : CCurlFile::GetMimeType - -> application/dash+xml 2023-04-22 19:05:19.355 T:27954634 info : Creating InputStream 2023-04-22 19:05:19.356 T:27954634 debug : ADDON: Dll Initializing - InputStream Adaptive 2023-04-22 19:05:19.356 T:27954634 debug : SECTION:LoadDLL(/Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/inputstream.adaptive.21.1.0.dylib) 2023-04-22 19:05:19.356 T:27954634 debug : Loading: /Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/inputstream.adaptive.21.1.0.dylib 2023-04-22 19:05:19.356 T:27954634 debug : AddOnLog: inputstream.adaptive: Open() 2023-04-22 19:05:19.356 T:27954634 debug : AddOnLog: inputstream.adaptive: Property found "inputstream.adaptive.license_key" value: [redacted] 2023-04-22 19:05:19.356 T:27954634 debug : AddOnLog: inputstream.adaptive: Property found "inputstream.adaptive.license_type" value: com.widevine.alpha 2023-04-22 19:05:19.356 T:27954634 debug : AddOnLog: inputstream.adaptive: Property found "inputstream.adaptive.manifest_type" value: mpd 2023-04-22 19:05:19.357 T:27952972 debug : Loading settings for /Volumes/tmm1-ssd/kodi-videos/mint.m3u 2023-04-22 19:05:19.359 T:27954634 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Type: Default 2023-04-22 19:05:19.359 T:27954634 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Configuration Resolution max: 0x0 Resolution max for secure decoder: 0x0 Bandwidth limits (bit/s): min 0, max 0 Ignore screen resolution: 0 Ignore screen resolution change: 0 2023-04-22 19:05:19.359 T:27954634 debug : AddOnLog: inputstream.adaptive: Setting NOSECUREDECODER value: 0 2023-04-22 19:05:19.359 T:27954634 debug : AddOnLog: inputstream.adaptive: Ignore HDCP status setting value: 1 2023-04-22 19:05:19.359 T:27954634 info : AddOnLog: inputstream.adaptive: [Repr. chooser] Resolution set: 2560x1308, max allowed: 2560x1308, Adjust refresh rate: 0 2023-04-22 19:05:19.359 T:27954634 debug : AddOnLog: inputstream.adaptive: Searching for decrypters in: /Applications/Kodi.app/Contents/Resources/Kodi/addons/inputstream.adaptive/ 2023-04-22 19:05:19.360 T:27954634 error : GetDirectory - Error getting /Applications/Kodi.app/Contents/Resources/Kodi/addons/inputstream.adaptive/ 2023-04-22 19:05:19.360 T:27954634 debug : AddOnLog: inputstream.adaptive: Searching for decrypters in: 2023-04-22 19:05:19.360 T:27954634 error : GetDirectory - Error getting 2023-04-22 19:05:19.360 T:27954634 debug : AddOnLog: inputstream.adaptive: Searching for decrypters in: /Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/ 2023-04-22 19:05:19.360 T:27954634 debug : AddOnLog: inputstream.adaptive: Found decrypter: /Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/libssd_wv.dylib 2023-04-22 19:05:19.360 T:27954634 debug : AddOnLog: inputstream.adaptive: Supported URN: urn:uuid:EDEF8BA9-79D6-4ACE-A3C8-27DCD51D21ED 2023-04-22 19:05:19.361 T:27954634 debug : CurlFile::Open - 2023-04-22 19:05:19.607 T:27954634 debug : CCurlFile::Open - Effective URL is https://dice-live-us.akamaized.net/exp=1682301919~acl=%2f*~id=d5f16201-5e51-4fec-aa8a-3262de9c99af~data=hdntl~hmac=b31cb90878015cdf37d29d8164365e6b272318443bd8b8a264d4134a9d6d3f7d/dash/live/2001830/237410-240341/manifest.mpd 2023-04-22 19:05:19.607 T:27954634 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/dash/live/2001830/237410-240341/manifest.mpd?hdnea=st=1682215506~exp=1682215546~acl=/dash/live/2001830/237410-240341/*~id=d5f16201-5e51-4fec-aa8a-3262de9c99af~data=d5f16201-5e51-4fec-aa8a-3262de9c99af~hmac=6688415e3c64cebb4597c15608233c0ec7515efd4c1efe46483f00346ebb1e82&opId=322&cc=US&ua=TW96aWxsYS81LjAgKE1hY2ludG9zaDsgSW50ZWwgTWFjIE9TIFggMTBfMTVfNykgQXBwbGVXZWJLaXQvNTM3LjM2IChLSFRNTCwgbGlrZSBHZWNrbykgQ2hyb21lLzExMC4wLjAuMCBTYWZhcmkvNTM3LjM2&sd=US-CA (downloaded 1315 byte, speed 2139812.70 byte/s) 2023-04-22 19:05:19.608 T:27954634 info : AddOnLog: inputstream.adaptive: Successfully parsed manifest file (Periods: 1, Streams in first period: 2, Type: live) 2023-04-22 19:05:19.608 T:27954634 debug : AddOnLog: inputstream.adaptive: New period, dispose sample decrypter and reinitialize 2023-04-22 19:05:19.608 T:27954634 debug : AddOnLog: inputstream.adaptive: Entering encryption section 2023-04-22 19:05:19.608 T:27954634 debug : AddOnLog: inputstream.adaptive: CDM version: 4.10.2557.0 2023-04-22 19:05:19.609 T:27954634 debug : AddOnLog: inputstream.adaptive: CDM is initialized: true 2023-04-22 19:05:19.609 T:27954634 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Screen resolution has changed: 2560x1308 2023-04-22 19:05:19.609 T:27954634 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Stream selection conditions Screen resolution: 2560x1308 (may be limited by settings) Initial bandwidth: 17118501 bit/s 2023-04-22 19:05:19.609 T:27954634 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Current average bandwidth: 17118501 bit/s (filtered to 15406650 bit/s) 2023-04-22 19:05:19.609 T:27954634 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Selected representation ID video_6000 (Bandwidth: 6000000 bit/s, Resolution: 1920x1080) 2023-04-22 19:05:19.609 T:27954634 debug : AddOnLog: inputstream.adaptive: Created AdaptiveStream [AS-13] with adaptation set ID: "0", stream type: video 2023-04-22 19:05:19.609 T:27954634 debug : AddOnLog: inputstream.adaptive: Created AdaptiveStream [AS-14] with adaptation set ID: "1", stream type: audio 2023-04-22 19:05:19.609 T:27954634 debug : AddOnLog: inputstream.adaptive: GetCapabilities() 2023-04-22 19:05:19.714 T:27954634 info : Creating Demuxer 2023-04-22 19:05:19.714 T:27954634 debug : AddOnLog: inputstream.adaptive: GetStreamIds() 2023-04-22 19:05:19.714 T:27954634 debug : AddOnLog: inputstream.adaptive: GetStream(1001) 2023-04-22 19:05:19.714 T:27954634 debug : AddOnLog: inputstream.adaptive: GetStream(1002) 2023-04-22 19:05:19.714 T:27954634 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27 2023-04-22 19:05:19.714 T:27954634 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018 2023-04-22 19:05:19.714 T:27954634 info : Opening stream: 1001 source: 256 2023-04-22 19:05:19.714 T:27954634 debug : AddOnLog: inputstream.adaptive: OpenStream(1001) 2023-04-22 19:05:19.715 T:27954634 debug : CurlFile::Open - 2023-04-22 19:05:19.761 T:27954634 debug : AddOnLog: inputstream.adaptive: [AS-13] Download finished: https://dice-live-us.akamaized.net/exp=1682301919~acl=%2f*~id=d5f16201-5e51-4fec-aa8a-3262de9c99af~data=hdntl~hmac=b31cb90878015cdf37d29d8164365e6b272318443bd8b8a264d4134a9d6d3f7d/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/init_video_6000.m4s (downloaded 1473 byte, speed 32021.00 byte/s) 2023-04-22 19:05:19.762 T:27954634 debug : AddOnLog: inputstream.adaptive: UpdateSampleDescription: codec 1635148593 2023-04-22 19:05:19.762 T:27954634 debug : AddOnLog: inputstream.adaptive: GetStream(1001) 2023-04-22 19:05:19.762 T:27954634 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27 2023-04-22 19:05:19.762 T:27954634 info : Creating video codec with codec id: 27 2023-04-22 19:05:19.762 T:27954634 info : CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2023-04-22 19:05:19.762 T:27954634 debug : CDVDVideoCodecFFmpeg - Updated codec: ff-h264 2023-04-22 19:05:19.762 T:27954634 debug : CVideoPlayerVideo::OpenStream - open stream with codec id: 27 2023-04-22 19:05:19.762 T:27954634 info : Creating video thread 2023-04-22 19:05:19.763 T:27954650 debug : Thread VideoPlayerVideo start, auto delete: false 2023-04-22 19:05:19.763 T:27954650 info : running thread: video_thread 2023-04-22 19:05:19.763 T:27954650 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2023-04-22 19:05:19.868 T:27954634 info : Opening stream: 1002 source: 256 2023-04-22 19:05:19.868 T:27954634 debug : AddOnLog: inputstream.adaptive: OpenStream(1002) 2023-04-22 19:05:19.869 T:27954634 debug : CurlFile::Open - 2023-04-22 19:05:19.913 T:27954634 debug : AddOnLog: inputstream.adaptive: [AS-14] Download finished: https://dice-live-us.akamaized.net/exp=1682301919~acl=%2f*~id=d5f16201-5e51-4fec-aa8a-3262de9c99af~data=hdntl~hmac=b31cb90878015cdf37d29d8164365e6b272318443bd8b8a264d4134a9d6d3f7d/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/init_audio_3000.m4s (downloaded 1391 byte, speed 31613.00 byte/s) 2023-04-22 19:05:19.914 T:27954634 debug : AddOnLog: inputstream.adaptive: UpdateSampleDescription: codec 1836069985 2023-04-22 19:05:19.914 T:27954634 debug : AddOnLog: inputstream.adaptive: GetStream(1002) 2023-04-22 19:05:19.914 T:27954634 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018 2023-04-22 19:05:19.914 T:27954634 info : Finding audio codec for: 86018 2023-04-22 19:05:19.914 T:27954634 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-04-22 19:05:19.914 T:27954634 info : OpenStream: Allowing max Out-Of-Sync Value of 10 ms 2023-04-22 19:05:19.914 T:27954634 info : Creating audio thread 2023-04-22 19:05:19.914 T:27954662 debug : Thread VideoPlayerAudio start, auto delete: false 2023-04-22 19:05:19.914 T:27954662 info : running thread: CVideoPlayerAudio::Process() 2023-04-22 19:05:19.915 T:27954662 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2023-04-22 19:05:19.915 T:27954634 debug : CVideoPlayer::SetCaching - caching state 2 2023-04-22 19:05:19.915 T:27954634 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-04-22 19:05:19.915 T:27954634 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2023-04-22 19:05:19.915 T:27954634 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2023-04-22 19:05:19.915 T:27954634 debug : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2023-04-22 19:05:19.915 T:27954243 debug : OnAVChange: CApplication::OnAVChange 2023-04-22 19:05:19.915 T:27954649 debug : CurlFile::Open - 2023-04-22 19:05:19.926 T:27954662 debug : CDVDAudio::Pause - pausing audio stream 2023-04-22 19:05:19.979 T:27954660 debug : CurlFile::Open - 2023-04-22 19:05:19.979 T:27954660 debug : easy_acquire - Created session to https://dice-live-us.akamaized.net 2023-04-22 19:05:20.221 T:27954649 debug : AddOnLog: inputstream.adaptive: [AS-13] Download finished: https://dice-live-us.akamaized.net/exp=1682301919~acl=%2f*~id=d5f16201-5e51-4fec-aa8a-3262de9c99af~data=hdntl~hmac=b31cb90878015cdf37d29d8164365e6b272318443bd8b8a264d4134a9d6d3f7d/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369251.m4s (downloaded 3420630 byte, speed 11215180.00 byte/s) 2023-04-22 19:05:20.222 T:27954649 debug : CurlFile::Open - 2023-04-22 19:05:20.248 T:27954634 info : Process - eof reading from demuxer 2023-04-22 19:05:20.248 T:27954634 info : CVideoPlayer::OnExit() 2023-04-22 19:05:20.248 T:27954634 info : VideoPlayer: eof, waiting for queues to empty 2023-04-22 19:05:20.248 T:27954634 debug : CVideoPlayer: updating file item stream details with available streams 2023-04-22 19:05:20.248 T:27954634 info : Closing stream player 1 2023-04-22 19:05:20.248 T:27954634 debug : AddOnLog: inputstream.adaptive: EnableStream(1002: false) 2023-04-22 19:05:20.286 T:27954660 debug : AddOnLog: inputstream.adaptive: [AS-14] The download has been cancelled: https://dice-live-us.akamaized.net/exp=1682301919~acl=%2f*~id=d5f16201-5e51-4fec-aa8a-3262de9c99af~data=hdntl~hmac=b31cb90878015cdf37d29d8164365e6b272318443bd8b8a264d4134a9d6d3f7d/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/media_audio_3000-280369251.m4s 2023-04-22 19:05:20.287 T:27954634 info : Waiting for audio thread to exit 2023-04-22 19:05:20.289 T:27887843 debug : Inhibiting OS screen saver 2023-04-22 19:05:20.293 T:27954662 info : thread end: CVideoPlayerAudio::OnExit() 2023-04-22 19:05:20.294 T:27954662 debug : Thread VideoPlayerAudio 0x70000d373000 terminating 2023-04-22 19:05:20.294 T:27954634 info : Closing audio device 2023-04-22 19:05:20.294 T:27954634 info : Deleting audio codec 2023-04-22 19:05:20.294 T:27954634 info : Closing stream player 2 2023-04-22 19:05:20.294 T:27954634 debug : AddOnLog: inputstream.adaptive: EnableStream(1001: false) 2023-04-22 19:05:20.625 T:27954649 error : AddOnLog: inputstream.adaptive: [AS-13] Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682301919~acl=%2f*~id=d5f16201-5e51-4fec-aa8a-3262de9c99af~data=hdntl~hmac=b31cb90878015cdf37d29d8164365e6b272318443bd8b8a264d4134a9d6d3f7d/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369252.m4s 2023-04-22 19:05:20.625 T:27954634 info : waiting for video thread to exit 2023-04-22 19:05:20.625 T:27954650 info : thread end: video_thread 2023-04-22 19:05:20.625 T:27954650 debug : Thread VideoPlayerVideo 0x70000ced8000 terminating 2023-04-22 19:05:20.625 T:27954634 info : deleting video codec 2023-04-22 19:05:20.626 T:27954634 debug : AddOnLog: inputstream.adaptive: Close() 2023-04-22 19:05:20.626 T:27954634 debug : AddOnLog: inputstream.adaptive: CSession::~CSession() 2023-04-22 19:05:20.626 T:27954634 debug : SECTION:UnloadDll(/Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/inputstream.adaptive.21.1.0.dylib) 2023-04-22 19:05:20.626 T:27954634 info : ADDON: Dll Destroyed - InputStream Adaptive 2023-04-22 19:05:20.626 T:27954634 debug : Thread VideoPlayer 0x70000c084000 terminating 2023-04-22 19:05:20.628 T:27952972 debug : OnPlayBackEnded: CApplicationPlayerCallback::OnPlayBackEnded 2023-04-22 19:05:20.634 T:27887843 info : CVideoPlayer::CloseFile() 2023-04-22 19:05:20.635 T:27887843 debug : DeleteRenderer - deleting renderer 2023-04-22 19:05:20.635 T:27887843 debug : LinuxRendererGL: Cleaning up GL resources 2023-04-22 19:05:20.635 T:27887843 info : VideoPlayer: waiting for threads to exit 2023-04-22 19:05:20.635 T:27887843 info : VideoPlayer: finished waiting 2023-04-22 19:05:20.635 T:27887843 info : CVideoPlayer::CloseFile() 2023-04-22 19:05:20.635 T:27887843 info : VideoPlayer: waiting for threads to exit 2023-04-22 19:05:20.635 T:27887843 info : VideoPlayer: finished waiting 2023-04-22 19:05:20.635 T:27887843 debug : Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 2023-04-22 19:05:20.635 T:27887843 debug : Audio ID3 tag processor - delete ~CVideoPlayerAudioID3 2023-04-22 19:05:20.641 T:27887843 debug : ------ Window Deinit (DialogBusy.xml) ------ 2023-04-22 19:05:20.800 T:27887843 debug : Uninhibiting OS screen saver ```

Possible Fix

To Reproduce

I can reproduce with specific live stream. It only airs for one hour every week, so its hard for me to test. But here is the MPD:

<?xml version="1.0" encoding="utf-8" ?>
<!--Endeavor Streaming Server 6.5.5.20230314.22 -->
<MPD type="dynamic" profiles="urn:mpeg:dash:profile:isoff-live:2011" maxSegmentDuration="PT12.000S" minBufferTime="PT6.000S" minimumUpdatePeriod="PT6.000S" suggestedPresentationDelay="PT18.000S" timeShiftBufferDepth="PT1M" availabilityStartTime="1970-01-01T00:00:00Z" publishTime="2023-04-23T02:06:10Z" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns="urn:mpeg:dash:schema:mpd:2011" xmlns:xlink="http://www.w3.org/1999/xlink" xmlns:cenc="urn:mpeg:cenc:2013" xsi:schemaLocation="urn:mpeg:DASH:schema:MPD:2011 http://standards.iso.org/ittf/PubliclyAvailableStandards/MPEG-DASH_schema_files/DASH-MPD.xsd">
    <Period id="22EA08FE4B2A489EA9F4274A8B2C8150" start="PT467282H17.383S">
        <AdaptationSet id="0" contentType="video" mimeType="video/mp4" minBandwidth="412000" maxBandwidth="6000000" minWidth="512" maxWidth="1920" minHeight="288" maxHeight="1080" minFrameRate="30000/1000" maxFrameRate="30000/1000" segmentAlignment="true" startWithSAP="1" subsegmentAlignment="true" subsegmentStartsWithSAP="1">
            <ContentProtection schemeIdUri="urn:mpeg:dash:mp4protection:2011" value="cenc" />
            <ContentProtection schemeIdUri="urn:uuid:9a04f079-9840-4286-ab92-e65be0885f95" value="cenc" />
            <ContentProtection schemeIdUri="urn:uuid:edef8ba9-79d6-4ace-a3c8-27dcd51d21ed" value="cenc" />
            <InbandEventStream schemeIdUri="urn:scte:scte35:2013:xml" value="1" />
            <Role schemeIdUri="urn:mpeg:dash:role:2011" value="main" />
            <SegmentTemplate timescale="1000000" presentationTimeOffset="1682215217383000" duration="6000000" startNumber="280369202" media="media_$RepresentationID$-$Number$.m4s" initialization="init_$RepresentationID$.m4s" />
            <Representation width="1920" height="1080" frameRate="30000/1000" sar="1:1" codecs="avc1.4D4029" id="video_6000" bandwidth="6000000">
                <BaseURL>exchange237410mlmpq_237410_6000/hRQvnNNlA/</BaseURL>
            </Representation>
            <Representation width="1280" height="720" frameRate="30000/1000" sar="1:1" codecs="avc1.4D4028" id="video_4500" bandwidth="4500000">
                <BaseURL>exchange237410mlmpq_237410_4500/hRQvnNNlA/</BaseURL>
            </Representation>
            <Representation width="1280" height="720" frameRate="30000/1000" sar="1:1" codecs="avc1.4D4020" id="video_3000" bandwidth="3000000">
                <BaseURL>exchange237410mlmpq_237410_3000/hRQvnNNlA/</BaseURL>
            </Representation>
            <Representation width="896" height="504" frameRate="30000/1000" sar="1:1" codecs="avc1.4D401F" id="video_1640" bandwidth="1640000">
                <BaseURL>exchange237410mlmpq_237410_1640/hRQvnNNlA/</BaseURL>
            </Representation>
            <Representation width="896" height="504" frameRate="30000/1000" sar="1:1" codecs="avc1.4D401F" id="video_1100" bandwidth="1100000">
                <BaseURL>exchange237410mlmpq_237410_1100/hRQvnNNlA/</BaseURL>
            </Representation>
            <Representation width="640" height="360" frameRate="30000/1000" sar="1:1" codecs="avc1.4D401E" id="video_728" bandwidth="728000">
                <BaseURL>exchange237410mlmpq_237410_728/hRQvnNNlA/</BaseURL>
            </Representation>
            <Representation width="512" height="288" frameRate="30000/1000" sar="1:1" codecs="avc1.4D4015" id="video_412" bandwidth="412000">
                <BaseURL>exchange237410mlmpq_237410_412/hRQvnNNlA/</BaseURL>
            </Representation>
        </AdaptationSet>
        <AdaptationSet id="1" contentType="audio" mimeType="audio/mp4" lang="eng" segmentAlignment="true" startWithSAP="1" subsegmentAlignment="true" subsegmentStartsWithSAP="1">
            <ContentProtection schemeIdUri="urn:mpeg:dash:mp4protection:2011" value="cenc" />
            <ContentProtection schemeIdUri="urn:uuid:9a04f079-9840-4286-ab92-e65be0885f95" value="cenc" />
            <ContentProtection schemeIdUri="urn:uuid:edef8ba9-79d6-4ace-a3c8-27dcd51d21ed" value="cenc" />
            <Role schemeIdUri="urn:mpeg:dash:role:2011" value="main" />
            <Role schemeIdUri="urn:neulion:audio:displayname" value="English" />
            <SegmentTemplate timescale="1000000" presentationTimeOffset="1682215217383000" duration="6000000" startNumber="280369202" media="media_$RepresentationID$-$Number$.m4s" initialization="init_$RepresentationID$.m4s" />
            <Representation audioSamplingRate="48000" codecs="mp4a.40.2" id="audio_3000" bandwidth="128000">
                <AudioChannelConfiguration schemeIdUri="urn:mpeg:dash:23003:3:audio_channel_configuration:2011" value="2" />
                <BaseURL>exchange237410mlmpq_237410_3000/hRQvnNNlA/</BaseURL>
            </Representation>
        </AdaptationSet>
    </Period>
    <UTCTiming schemeIdUri="urn:mpeg:dash:utc:direct:2014" value="2023-04-23T02:06:11.383000Z" />
</MPD>

Debuglog

see above

MPD/M3U8s/ISM

see above

Screenshots

Here are some links or screenshots to help explain the problem:

Additional context or screenshots (if appropriate)

Here is some additional context or explanation that might help:

Your Environment

Used Operating system:

note: Once the issue is made we require you to update it with new information should that be required. Team Kodi will consider your problem report however, we will not make any promises the problem will be solved.

tmm1 commented 1 year ago
working log ``` 2023-04-22 19:17:18.768 T:27994321 debug : Keyboard: scancode: 0x7d, sym: 0x112, unicode: 0xf701, modifier: 0x0 2023-04-22 19:17:18.768 T:27994321 debug : HandleKey: down (0xf081) pressed, window 10025, action is Down 2023-04-22 19:17:18.801 T:27994321 debug : Keyboard: scancode: 0x7d, sym: 0x112, unicode: 0x00, modifier: 0x0 2023-04-22 19:17:18.888 T:27994321 debug : Keyboard: scancode: 0x7d, sym: 0x112, unicode: 0xf701, modifier: 0x0 2023-04-22 19:17:18.888 T:27994321 debug : HandleKey: down (0xf081) pressed, window 10025, action is Down 2023-04-22 19:17:18.954 T:27994321 debug : Keyboard: scancode: 0x7d, sym: 0x112, unicode: 0x00, modifier: 0x0 2023-04-22 19:17:19.079 T:27994321 debug : Keyboard: scancode: 0x7d, sym: 0x112, unicode: 0xf701, modifier: 0x0 2023-04-22 19:17:19.080 T:27994321 debug : HandleKey: down (0xf081) pressed, window 10025, action is Down 2023-04-22 19:17:19.103 T:27994321 debug : Keyboard: scancode: 0x7d, sym: 0x112, unicode: 0x00, modifier: 0x0 2023-04-22 19:17:19.538 T:27994321 debug : Keyboard: scancode: 0x24, sym: 0x0d, unicode: 0x0d, modifier: 0x0 2023-04-22 19:17:19.580 T:27994321 debug : Keyboard: scancode: 0x24, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-04-22 19:17:19.580 T:27994321 debug : HandleKey: return (0xf00d) pressed, window 10025, action is Select 2023-04-22 19:17:19.581 T:27994321 debug : OnPlayMedia /Volumes/tmm1-ssd/kodi-videos/mint.m3u 2023-04-22 19:17:19.582 T:27994321 debug : easy_acquire - Created session to https://dice-live-us.akamaized.net 2023-04-22 19:17:20.138 T:27994321 debug : CCurlFile::GetMimeType - -> application/dash+xml 2023-04-22 19:17:20.141 T:27994321 debug : CPlayerCoreFactory::GetPlayers(https://dice-live-us.akamaized.net/dash/live/2001830/237410-240341/manifest.mpd?hdnea=st=1682216227~exp=1682216267~acl=/dash/live/2001830/237410-240341/*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=8468146e-f000-4a29-ba1d-61f723094ef9~hmac=22b166466eee5338324c866834784892afe9ce7d1fb0f0d0680a02e0cd9151cb&opId=322&cc=US&ua=TW96aWxsYS81LjAgKE1hY2ludG9zaDsgSW50ZWwgTWFjIE9TIFggMTBfMTVfNykgQXBwbGVXZWJLaXQvNTM3LjM2IChLSFRNTCwgbGlrZSBHZWNrbykgQ2hyb21lLzExMC4wLjAuMCBTYWZhcmkvNTM3LjM2&sd=US-CA) 2023-04-22 19:17:20.141 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2023-04-22 19:17:20.141 T:27994321 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2023-04-22 19:17:20.141 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2023-04-22 19:17:20.141 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: matches rule: streams 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: mp2 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2023-04-22 19:17:20.142 T:27994321 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2023-04-22 19:17:20.143 T:27994321 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2023-04-22 19:17:20.143 T:27994321 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2023-04-22 19:17:20.143 T:27994321 debug : Radio UECP (RDS) Processor - new CDVDRadioRDSData 2023-04-22 19:17:20.143 T:27994321 debug : Audio ID3 tag processor - new CVideoPlayerAudioID3 2023-04-22 19:17:20.143 T:27994321 info : VideoPlayer::OpenFile: /Volumes/tmm1-ssd/kodi-videos/mint.m3u 2023-04-22 19:17:20.144 T:27994909 debug : Thread VideoPlayer start, auto delete: false 2023-04-22 19:17:20.144 T:27994321 debug : OnPlayBackStarted: CApplication::OnPlayBackStarted 2023-04-22 19:17:20.144 T:27994910 debug : Thread BackgroundLoader start, auto delete: false 2023-04-22 19:17:20.144 T:27994321 debug : [threads] setting priority is not supported on this platform 2023-04-22 19:17:20.150 T:27994321 debug : CVideoGUIInfo::InitCurrentItem(/Volumes/tmm1-ssd/kodi-videos/mint.m3u) 2023-04-22 19:17:20.153 T:27994910 debug : Thread BackgroundLoader 0x700010626000 terminating 2023-04-22 19:17:20.157 T:27994321 debug : CPlayerGUIInfo::InitCurrentItem(/Volumes/tmm1-ssd/kodi-videos/mint.m3u) 2023-04-22 19:17:20.258 T:27994321 debug : ------ Window Init (DialogBusy.xml) ------ 2023-04-22 19:17:20.258 T:27994321 debug : Window DialogBusy.xml was already loaded 2023-04-22 19:17:20.258 T:27994321 debug : Alloc resources: 0.01 ms 2023-04-22 19:17:20.374 T:27994909 debug : CCurlFile::GetMimeType - -> application/dash+xml 2023-04-22 19:17:20.375 T:27994909 info : Creating InputStream 2023-04-22 19:17:20.375 T:27994909 debug : ADDON: Dll Initializing - InputStream Adaptive 2023-04-22 19:17:20.375 T:27994909 debug : SECTION:LoadDLL(/Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/inputstream.adaptive.21.1.0.dylib) 2023-04-22 19:17:20.375 T:27994909 debug : Loading: /Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/inputstream.adaptive.21.1.0.dylib 2023-04-22 19:17:20.377 T:27994465 debug : Loading settings for /Volumes/tmm1-ssd/kodi-videos/mint.m3u 2023-04-22 19:17:20.758 T:27994909 debug : AddOnLog: inputstream.adaptive: Open() 2023-04-22 19:17:20.758 T:27994909 debug : AddOnLog: inputstream.adaptive: Property found "inputstream.adaptive.license_key" value: [redacted] 2023-04-22 19:17:20.758 T:27994909 debug : AddOnLog: inputstream.adaptive: Property found "inputstream.adaptive.license_type" value: com.widevine.alpha 2023-04-22 19:17:20.758 T:27994909 debug : AddOnLog: inputstream.adaptive: Property found "inputstream.adaptive.manifest_type" value: mpd 2023-04-22 19:17:20.761 T:27994909 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Type: Default 2023-04-22 19:17:20.761 T:27994909 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Configuration Resolution max: 0x0 Resolution max for secure decoder: 0x0 Bandwidth limits (bit/s): min 0, max 0 Ignore screen resolution: 0 Ignore screen resolution change: 0 2023-04-22 19:17:20.762 T:27994909 debug : AddOnLog: inputstream.adaptive: Setting NOSECUREDECODER value: 0 2023-04-22 19:17:20.762 T:27994909 debug : AddOnLog: inputstream.adaptive: Ignore HDCP status setting value: 1 2023-04-22 19:17:20.762 T:27994909 info : AddOnLog: inputstream.adaptive: [Repr. chooser] Resolution set: 2560x1308, max allowed: 2560x1308, Adjust refresh rate: 0 2023-04-22 19:17:20.762 T:27994909 debug : AddOnLog: inputstream.adaptive: Searching for decrypters in: /Applications/Kodi.app/Contents/Resources/Kodi/addons/inputstream.adaptive/ 2023-04-22 19:17:20.762 T:27994909 error : GetDirectory - Error getting /Applications/Kodi.app/Contents/Resources/Kodi/addons/inputstream.adaptive/ 2023-04-22 19:17:20.762 T:27994909 debug : AddOnLog: inputstream.adaptive: Searching for decrypters in: 2023-04-22 19:17:20.762 T:27994909 error : GetDirectory - Error getting 2023-04-22 19:17:20.762 T:27994909 debug : AddOnLog: inputstream.adaptive: Searching for decrypters in: /Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/ 2023-04-22 19:17:20.913 T:27994909 debug : AddOnLog: inputstream.adaptive: Found decrypter: /Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/libssd_wv.dylib 2023-04-22 19:17:20.913 T:27994909 debug : AddOnLog: inputstream.adaptive: Supported URN: urn:uuid:EDEF8BA9-79D6-4ACE-A3C8-27DCD51D21ED 2023-04-22 19:17:20.913 T:27994909 debug : CurlFile::Open - 2023-04-22 19:17:21.298 T:27994909 debug : CCurlFile::Open - Effective URL is https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/manifest.mpd 2023-04-22 19:17:21.298 T:27994909 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/dash/live/2001830/237410-240341/manifest.mpd?hdnea=st=1682216227~exp=1682216267~acl=/dash/live/2001830/237410-240341/*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=8468146e-f000-4a29-ba1d-61f723094ef9~hmac=22b166466eee5338324c866834784892afe9ce7d1fb0f0d0680a02e0cd9151cb&opId=322&cc=US&ua=TW96aWxsYS81LjAgKE1hY2ludG9zaDsgSW50ZWwgTWFjIE9TIFggMTBfMTVfNykgQXBwbGVXZWJLaXQvNTM3LjM2IChLSFRNTCwgbGlrZSBHZWNrbykgQ2hyb21lLzExMC4wLjAuMCBTYWZhcmkvNTM3LjM2&sd=US-CA (downloaded 1315 byte, speed 1365142.29 byte/s) 2023-04-22 19:17:21.299 T:27994909 info : AddOnLog: inputstream.adaptive: Successfully parsed manifest file (Periods: 1, Streams in first period: 2, Type: live) 2023-04-22 19:17:21.299 T:27994909 debug : AddOnLog: inputstream.adaptive: New period, dispose sample decrypter and reinitialize 2023-04-22 19:17:21.299 T:27994909 debug : AddOnLog: inputstream.adaptive: Entering encryption section 2023-04-22 19:17:21.301 T:27994909 debug : AddOnLog: inputstream.adaptive: CDM version: 4.10.2557.0 2023-04-22 19:17:21.366 T:27994909 debug : AddOnLog: inputstream.adaptive: CDM is initialized: true 2023-04-22 19:17:21.366 T:27994909 debug : AddOnLog: inputstream.adaptive: Searching PSSH data in FILE 2023-04-22 19:17:21.366 T:27994909 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Screen resolution has changed: 2560x1308 2023-04-22 19:17:21.366 T:27994909 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Current average bandwidth: 10921138 bit/s (filtered to 9829024 bit/s) 2023-04-22 19:17:21.366 T:27994909 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Selected representation ID video_6000 (Bandwidth: 6000000 bit/s, Resolution: 1920x1080) 2023-04-22 19:17:21.367 T:27994909 debug : CurlFile::Open - 2023-04-22 19:17:21.422 T:27994909 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/init_video_6000.m4s (downloaded 1473 byte, speed 27277.00 byte/s) 2023-04-22 19:17:21.423 T:27994909 debug : AddOnLog: inputstream.adaptive: Initializing stream with KID: 315e44a69edf464fb01bfdf2b6fc26ef 2023-04-22 19:17:21.551 T:27994909 debug : AddOnLog: inputstream.adaptive: CDMMessage: 1 arrived! 2023-04-22 19:17:21.551 T:27994909 debug : AddOnLog: inputstream.adaptive: Opened widevine session ID: C3E04E579641D7617E4548BBC6C13F53 2023-04-22 19:17:21.552 T:27994909 debug : CurlFile::Open - 2023-04-22 19:17:21.553 T:27994909 debug : easy_acquire - Created session to https://shield-drm.imggaming.com 2023-04-22 19:17:22.753 T:27994909 debug : AddOnLog: inputstream.adaptive: CDMMessage: 2 arrived! 2023-04-22 19:17:22.754 T:27994909 debug : AddOnLog: inputstream.adaptive: OnSessionKeysChange: Sessionkey 315E44A69EDF464FB01BFDF2B6FC26EF status: 0 syscode: 0 2023-04-22 19:17:22.754 T:27994909 debug : AddOnLog: inputstream.adaptive: CDMMessage: 3 arrived! 2023-04-22 19:17:22.755 T:27994909 debug : AddOnLog: inputstream.adaptive: License update successful 2023-04-22 19:17:22.762 T:27994909 debug : AddOnLog: inputstream.adaptive: LogDecryptError: Decrypt failed with error: 2 and key: 315E44A69EDF464FB01BFDF2B6FC26EF 2023-04-22 19:17:22.762 T:27994909 debug : AddOnLog: inputstream.adaptive: GetCapabilities: Single decrypt possible 2023-04-22 19:17:22.762 T:27994909 debug : AddOnLog: inputstream.adaptive: Searching PSSH data in FILE 2023-04-22 19:17:22.762 T:27994909 debug : CurlFile::Open - 2023-04-22 19:17:22.819 T:27994909 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/init_audio_3000.m4s (downloaded 1391 byte, speed 24839.00 byte/s) 2023-04-22 19:17:22.820 T:27994909 debug : AddOnLog: inputstream.adaptive: Initializing stream with KID: 315e44a69edf464fb01bfdf2b6fc26ef 2023-04-22 19:17:22.854 T:27994909 debug : AddOnLog: inputstream.adaptive: CDMMessage: 1 arrived! 2023-04-22 19:17:22.854 T:27994909 debug : AddOnLog: inputstream.adaptive: Opened widevine session ID: 62DC9D0FB3F4AD0EC56B6B1C2D98EEB6 2023-04-22 19:17:22.855 T:27994909 debug : CurlFile::Open - 2023-04-22 19:17:23.249 T:27994909 debug : AddOnLog: inputstream.adaptive: CDMMessage: 2 arrived! 2023-04-22 19:17:23.249 T:27994909 debug : AddOnLog: inputstream.adaptive: OnSessionKeysChange: Sessionkey 315E44A69EDF464FB01BFDF2B6FC26EF status: 0 syscode: 0 2023-04-22 19:17:23.249 T:27994909 debug : AddOnLog: inputstream.adaptive: CDMMessage: 3 arrived! 2023-04-22 19:17:23.250 T:27994909 debug : AddOnLog: inputstream.adaptive: License update successful 2023-04-22 19:17:23.257 T:27994909 debug : AddOnLog: inputstream.adaptive: LogDecryptError: Decrypt failed with error: 2 and key: 315E44A69EDF464FB01BFDF2B6FC26EF 2023-04-22 19:17:23.258 T:27994909 debug : AddOnLog: inputstream.adaptive: GetCapabilities: Single decrypt possible 2023-04-22 19:17:23.258 T:27994909 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Stream selection conditions Screen resolution: 2560x1308 (may be limited by settings) Initial bandwidth: 10921138 bit/s 2023-04-22 19:17:23.258 T:27994909 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Current average bandwidth: 10921138 bit/s (filtered to 9829024 bit/s) 2023-04-22 19:17:23.258 T:27994909 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Selected representation ID video_6000 (Bandwidth: 6000000 bit/s, Resolution: 1920x1080) 2023-04-22 19:17:23.258 T:27994909 debug : AddOnLog: inputstream.adaptive: GetCapabilities() 2023-04-22 19:17:23.364 T:27994909 info : Creating Demuxer 2023-04-22 19:17:23.364 T:27994909 debug : AddOnLog: inputstream.adaptive: GetStreamIds() 2023-04-22 19:17:23.364 T:27994909 debug : AddOnLog: inputstream.adaptive: GetStream(1001) 2023-04-22 19:17:23.364 T:27994909 debug : AddOnLog: inputstream.adaptive: GetStream(1002) 2023-04-22 19:17:23.364 T:27994909 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27 2023-04-22 19:17:23.364 T:27994909 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018 2023-04-22 19:17:23.364 T:27994909 info : Opening stream: 1001 source: 256 2023-04-22 19:17:23.365 T:27994909 debug : AddOnLog: inputstream.adaptive: OpenStream(1001) 2023-04-22 19:17:23.365 T:27994909 debug : CurlFile::Open - 2023-04-22 19:17:23.425 T:27994909 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/init_video_6000.m4s (downloaded 1473 byte, speed 24966.00 byte/s) 2023-04-22 19:17:23.426 T:27994909 debug : AddOnLog: inputstream.adaptive: UpdateSampleDescription: codec 1635148593 2023-04-22 19:17:23.426 T:27994909 debug : AddOnLog: inputstream.adaptive: GetStream(1001) 2023-04-22 19:17:23.426 T:27994909 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27 2023-04-22 19:17:23.426 T:27994909 info : Creating video codec with codec id: 27 2023-04-22 19:17:23.426 T:27994909 info : CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2023-04-22 19:17:23.426 T:27994909 debug : CDVDVideoCodecFFmpeg - Updated codec: ff-h264 2023-04-22 19:17:23.426 T:27994909 debug : CVideoPlayerVideo::OpenStream - open stream with codec id: 27 2023-04-22 19:17:23.426 T:27994909 info : Creating video thread 2023-04-22 19:17:23.426 T:27995007 debug : Thread VideoPlayerVideo start, auto delete: false 2023-04-22 19:17:23.427 T:27995007 info : running thread: video_thread 2023-04-22 19:17:23.427 T:27995007 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2023-04-22 19:17:23.531 T:27994909 info : Opening stream: 1002 source: 256 2023-04-22 19:17:23.532 T:27994909 debug : AddOnLog: inputstream.adaptive: OpenStream(1002) 2023-04-22 19:17:23.532 T:27994909 debug : CurlFile::Open - 2023-04-22 19:17:23.588 T:27994909 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/init_audio_3000.m4s (downloaded 1391 byte, speed 24839.00 byte/s) 2023-04-22 19:17:23.589 T:27994909 debug : AddOnLog: inputstream.adaptive: UpdateSampleDescription: codec 1836069985 2023-04-22 19:17:23.589 T:27994909 debug : AddOnLog: inputstream.adaptive: GetStream(1002) 2023-04-22 19:17:23.589 T:27994909 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018 2023-04-22 19:17:23.589 T:27994909 info : Finding audio codec for: 86018 2023-04-22 19:17:23.590 T:27994909 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-04-22 19:17:23.590 T:27994909 info : OpenStream: Allowing max Out-Of-Sync Value of 10 ms 2023-04-22 19:17:23.590 T:27994909 info : Creating audio thread 2023-04-22 19:17:23.591 T:27995023 debug : Thread VideoPlayerAudio start, auto delete: false 2023-04-22 19:17:23.591 T:27995023 info : running thread: CVideoPlayerAudio::Process() 2023-04-22 19:17:23.591 T:27995023 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2023-04-22 19:17:23.591 T:27994909 debug : CVideoPlayer::SetCaching - caching state 2 2023-04-22 19:17:23.591 T:27994909 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-04-22 19:17:23.591 T:27994909 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2023-04-22 19:17:23.591 T:27994909 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2023-04-22 19:17:23.591 T:27994463 debug : OnAVChange: CApplication::OnAVChange 2023-04-22 19:17:23.591 T:27994909 debug : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2023-04-22 19:17:23.592 T:27995003 debug : CurlFile::Open - 2023-04-22 19:17:23.603 T:27995023 debug : CDVDAudio::Pause - pausing audio stream 2023-04-22 19:17:23.774 T:27994321 debug : Inhibiting OS screen saver 2023-04-22 19:17:23.792 T:27995016 debug : CurlFile::Open - 2023-04-22 19:17:23.792 T:27995016 debug : easy_acquire - Created session to https://dice-live-us.akamaized.net 2023-04-22 19:17:24.009 T:27995007 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2023-04-22 19:17:24.009 T:27995007 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-04-22 19:17:24.010 T:27995007 debug : CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vtb 2023-04-22 19:17:24.023 T:27995023 debug : CVideoPlayerAudio: stream props changed, checking for passthrough 2023-04-22 19:17:24.024 T:27995023 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-04-22 19:17:24.024 T:27995023 info : Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through) 2023-04-22 19:17:24.024 T:27995023 debug : CVideoPlayerAudio:: synctype set to 1: resample 2023-04-22 19:17:24.041 T:27994384 info : CActiveAESink::OpenSink - initialize sink 2023-04-22 19:17:24.044 T:27995016 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/media_audio_3000-280369371.m4s (downloaded 103234 byte, speed 411290.00 byte/s) 2023-04-22 19:17:24.045 T:27995016 debug : CurlFile::Open - 2023-04-22 19:17:24.052 T:27995003 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369371.m4s (downloaded 4479498 byte, speed 9759254.00 byte/s) 2023-04-22 19:17:24.053 T:27995003 debug : CurlFile::Open - 2023-04-22 19:17:24.138 T:27995564 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Current average bandwidth: 44497585 bit/s (filtered to 40047826 bit/s) 2023-04-22 19:17:24.169 T:27995007 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-04-22 19:17:24.174 T:27995007 debug : CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 30.00. 2023-04-22 19:17:24.176 T:27994321 debug : DeleteRenderer - deleting renderer 2023-04-22 19:17:24.176 T:27994321 debug : LinuxRendererGL: Cleaning up GL resources 2023-04-22 19:17:24.179 T:27994321 info : GL: Using CVBREF render method 2023-04-22 19:17:24.179 T:27994321 debug : GL: Requested render method: 0 2023-04-22 19:17:24.180 T:27994321 debug : GL: using shader format: NV12 2023-04-22 19:17:24.180 T:27994321 debug : GL: using tonemap method: reinhard 2023-04-22 19:17:24.180 T:27994321 info : GL: Selecting YUV 2 RGB shader 2023-04-22 19:17:24.182 T:27994321 info : Using GL_TEXTURE_RECTANGLE 2023-04-22 19:17:24.182 T:27994321 debug : CRenderManager::Configure - 5 2023-04-22 19:17:24.182 T:27994586 debug : OnAVChange: CApplication::OnAVChange 2023-04-22 19:17:24.182 T:27994909 debug : CVideoPlayer::HandleMessages - player started 2 2023-04-22 19:17:24.184 T:27994321 info : GL: Using CVBREF render method 2023-04-22 19:17:24.184 T:27994321 debug : GL: Requested render method: 0 2023-04-22 19:17:24.184 T:27994321 debug : GL: using shader format: NV12 2023-04-22 19:17:24.184 T:27994321 debug : GL: using tonemap method: reinhard 2023-04-22 19:17:24.185 T:27994321 info : GL: Selecting YUV 2 RGB shader 2023-04-22 19:17:24.195 T:27994384 debug : CCoreAudioStream::Close: Restoring original physical format for stream 0x53. ([lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz)) 2023-04-22 19:17:24.313 T:27995016 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/media_audio_3000-280369372.m4s (downloaded 103716 byte, speed 388449.00 byte/s) 2023-04-22 19:17:24.313 T:27995016 debug : CurlFile::Open - 2023-04-22 19:17:24.549 T:27994384 debug : CCoreAudioStream::SetPhysicalFormat: Physical format for stream 0x53. now active ([lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz)) 2023-04-22 19:17:24.550 T:27994384 debug : CCoreAudioStream::Close: Closed stream 0x53. 2023-04-22 19:17:24.551 T:27994384 debug : CActiveAESink::OpenSink - trying to open device DARWINOSX:default 2023-04-22 19:17:24.556 T:27994384 info : virtual bool CAESinkDARWINOSX::Initialize(AEAudioFormat &, std::string &): Opening default device Built-in Output 2023-04-22 19:17:24.558 T:27994384 debug : FindSuitableFormatForStream: Finding stream for format AE_FMT_FLOATP 2023-04-22 19:17:24.558 T:27994384 debug : FindSuitableFormatForStream: Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (96000Hz) rated 1106.000000 2023-04-22 19:17:24.558 T:27994384 debug : FindSuitableFormatForStream: Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (48000Hz) rated 1508.000000 2023-04-22 19:17:24.558 T:27994384 debug : FindSuitableFormatForStream: Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz) rated 89.250000 2023-04-22 19:17:24.558 T:27994384 debug : Initialize: Selected stream[0] - id: 0X53, Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (48000Hz) 2023-04-22 19:17:24.559 T:27994384 debug : CCoreAudioDevice::SupportsMixing: Device mixing support : 'No'. 2023-04-22 19:17:24.559 T:27994384 debug : CCoreAudioStream::Open: Opened stream 0x53. 2023-04-22 19:17:24.559 T:27994384 debug : Initialize: Previous Virtual Format: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (44100Hz) 2023-04-22 19:17:24.559 T:27994384 debug : Initialize: Previous Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz) 2023-04-22 19:17:24.922 T:27995332 info : CCoreAudioStream::HardwareStreamListener: Hardware physical format changed to [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (48000Hz) 2023-04-22 19:17:24.922 T:27995332 info : CCoreAudioStream::HardwareStreamListener: Hardware virtual format changed to [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (48000Hz) 2023-04-22 19:17:24.927 T:27994384 debug : CCoreAudioStream::SetPhysicalFormat: Physical format for stream 0x53. now active ([lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (48000Hz)) 2023-04-22 19:17:24.927 T:27994384 debug : Initialize: New Virtual Format: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (48000Hz) 2023-04-22 19:17:24.927 T:27994384 debug : Initialize: New Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (48000Hz) 2023-04-22 19:17:24.929 T:27994384 debug : GetAEChannelMap Engine requests layout FL, FR 2023-04-22 19:17:24.929 T:27994384 debug : GetAEChannelMap trying to map to stereo layout: [Left] [Right] 2023-04-22 19:17:24.929 T:27994384 debug : GetAEChannelMap mapped channels to layout FL, FR 2023-04-22 19:17:24.929 T:27994384 debug : Initialize: using buffer size: 16384 (0.042667 ms) 2023-04-22 19:17:24.952 T:27994384 debug : CActiveAESink::OpenSink - DARWINOSX Initialized: 2023-04-22 19:17:24.952 T:27994384 debug : Output Device : Default 2023-04-22 19:17:24.952 T:27994384 debug : Sample Rate : 48000 2023-04-22 19:17:24.952 T:27994384 debug : Sample Format : AE_FMT_FLOAT 2023-04-22 19:17:24.952 T:27994384 debug : Channel Count : 2 2023-04-22 19:17:24.953 T:27994384 debug : Channel Layout: FL, FR 2023-04-22 19:17:24.953 T:27994384 debug : Frames : 512 2023-04-22 19:17:24.953 T:27994384 debug : Frame Size : 8 2023-04-22 19:17:24.954 T:27994383 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-04-22 19:17:24.955 T:27994909 info : Skipped 1 duplicate messages.. 2023-04-22 19:17:24.955 T:27994909 debug : CVideoPlayer::HandleMessages - player started 1 2023-04-22 19:17:24.955 T:27994909 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-04-22 19:17:24.955 T:27994465 debug : OnAVChange: CApplication::OnAVChange 2023-04-22 19:17:24.955 T:27994909 debug : VideoPlayer::Sync - Audio - pts: 1682216231698666.000000, cache: 387604.189316, totalcache: 642666.697502 2023-04-22 19:17:24.955 T:27994909 debug : VideoPlayer::Sync - Video - pts: 1682216231449666.250000, cache: 50000.000000, totalcache: 100000.000000 2023-04-22 19:17:24.956 T:27995023 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1682216230655999.250000), level: 86, cache: 387604.189316 2023-04-22 19:17:24.956 T:27995023 debug : CDVDAudio::Resume - resume audio stream 2023-04-22 19:17:24.956 T:27994383 debug : ActiveAE - start sync of audio stream 2023-04-22 19:17:24.956 T:27995007 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1682216230655999.250000) 2023-04-22 19:17:24.956 T:27995007 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-04-22 19:17:24.958 T:27994465 debug : OnAVStarted: CApplication::OnAVStarted 2023-04-22 19:17:24.962 T:27995007 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 1 2023-04-22 19:17:24.969 T:27994321 debug : Activating window ID: 12005 2023-04-22 19:17:24.970 T:27994321 debug : ------ Window Deinit (MyVideoNav.xml) ------ 2023-04-22 19:17:24.970 T:27994321 debug : FreeVisualisation() done 2023-04-22 19:17:24.971 T:27994321 debug : ------ Window Init (VideoFullScreen.xml) ------ 2023-04-22 19:17:24.971 T:27994321 info : Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 2023-04-22 19:17:24.973 T:27994321 debug : Skin file /Applications/Kodi.app/Contents/Resources/Kodi/addons/skin.estuary/xml/VideoFullScreen.xml loaded in 1.97 ms 2023-04-22 19:17:24.973 T:27994321 debug : Alloc resources: 2.07 ms (2.07 ms skin load) 2023-04-22 19:17:24.979 T:27994321 debug : ------ Window Deinit (DialogBusy.xml) ------ 2023-04-22 19:17:25.066 T:27994383 debug : ActiveAE::SyncStream - average error of 668.517552, start adjusting 2023-04-22 19:17:25.178 T:27995016 error : AddOnLog: inputstream.adaptive: Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/media_audio_3000-280369373.m4s 2023-04-22 19:17:25.217 T:27995003 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369372.m4s (downloaded 4431731 byte, speed 3807329.00 byte/s) 2023-04-22 19:17:25.218 T:27995003 debug : CurlFile::Open - 2023-04-22 19:17:25.486 T:27995007 warning : OutputPicture - timeout waiting for buffer 2023-04-22 19:17:25.669 T:27995003 error : AddOnLog: inputstream.adaptive: Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369373.m4s 2023-04-22 19:17:25.728 T:27994383 debug : ActiveAE::SyncStream - average error 22.959692, last average error: 668.517552 2023-04-22 19:17:25.834 T:27994383 debug : ActiveAE::SyncStream - average error of -26.222998, start adjusting 2023-04-22 19:17:25.834 T:27994383 debug : ActiveAE::SyncStream - average error -15.556331 below threshold of 30.000000 2023-04-22 19:17:25.840 T:27995007 info : CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 33333 2023-04-22 19:17:26.183 T:27995016 warning : AddOnLog: inputstream.adaptive: Segment download failed, attempt 2... 2023-04-22 19:17:26.184 T:27995016 debug : CurlFile::Open - 2023-04-22 19:17:26.428 T:27995016 error : AddOnLog: inputstream.adaptive: Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/media_audio_3000-280369373.m4s 2023-04-22 19:17:26.673 T:27995003 warning : AddOnLog: inputstream.adaptive: Segment download failed, attempt 2... 2023-04-22 19:17:26.674 T:27995003 debug : CurlFile::Open - 2023-04-22 19:17:26.822 T:27995003 error : AddOnLog: inputstream.adaptive: Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369373.m4s 2023-04-22 19:17:27.155 T:27994321 debug : ------ Window Init (VideoOSD.xml) ------ 2023-04-22 19:17:27.156 T:27994321 info : Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY 2023-04-22 19:17:27.167 T:27994321 debug : Skin file /Applications/Kodi.app/Contents/Resources/Kodi/addons/skin.estuary/xml/VideoOSD.xml loaded in 11.16 ms 2023-04-22 19:17:27.193 T:27994321 debug : Alloc resources: 37.56 ms (11.33 ms skin load) 2023-04-22 19:17:27.195 T:27994321 debug : ------ Window Init (Pointer.xml) ------ 2023-04-22 19:17:27.195 T:27994321 debug : ------ Window Init (DialogSeekBar.xml) ------ 2023-04-22 19:17:27.195 T:27994321 debug : Window DialogSeekBar.xml was already loaded 2023-04-22 19:17:27.195 T:27994321 debug : Alloc resources: 0.06 ms 2023-04-22 19:17:27.196 T:27994321 debug : ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 2023-04-22 19:17:27.196 T:27994321 debug : Window Custom_1109_TopBarOverlay.xml was already loaded 2023-04-22 19:17:27.197 T:27994321 debug : Alloc resources: 0.03 ms 2023-04-22 19:17:27.433 T:27995016 warning : AddOnLog: inputstream.adaptive: Segment download failed, attempt 3... 2023-04-22 19:17:27.433 T:27995016 debug : CurlFile::Open - 2023-04-22 19:17:27.629 T:27995016 error : AddOnLog: inputstream.adaptive: Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/media_audio_3000-280369373.m4s 2023-04-22 19:17:27.827 T:27995003 warning : AddOnLog: inputstream.adaptive: Segment download failed, attempt 3... 2023-04-22 19:17:27.827 T:27995003 debug : CurlFile::Open - 2023-04-22 19:17:28.017 T:27995003 error : AddOnLog: inputstream.adaptive: Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369373.m4s 2023-04-22 19:17:28.633 T:27995016 warning : AddOnLog: inputstream.adaptive: Segment download failed, attempt 4... 2023-04-22 19:17:28.634 T:27995016 debug : CurlFile::Open - 2023-04-22 19:17:28.757 T:27995016 error : AddOnLog: inputstream.adaptive: Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/media_audio_3000-280369373.m4s 2023-04-22 19:17:29.021 T:27995003 warning : AddOnLog: inputstream.adaptive: Segment download failed, attempt 4... 2023-04-22 19:17:29.022 T:27995003 debug : CurlFile::Open - 2023-04-22 19:17:29.145 T:27995003 error : AddOnLog: inputstream.adaptive: Download failed with error 404: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369373.m4s 2023-04-22 19:17:29.434 T:27996158 debug : AddOnLog: inputstream.adaptive: [Repr. chooser] Current average bandwidth: 39817934 bit/s (filtered to 35836140 bit/s) 2023-04-22 19:17:29.662 T:27995007 debug : CPtsTracker: detected pattern of length 1: 33333.33, frameduration: 33333.333333 2023-04-22 19:17:29.761 T:27995016 warning : AddOnLog: inputstream.adaptive: Segment download failed, attempt 5... 2023-04-22 19:17:29.762 T:27995016 debug : CurlFile::Open - 2023-04-22 19:17:29.998 T:27995016 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_3000/hRQvnNNlA/media_audio_3000-280369373.m4s (downloaded 102175 byte, speed 434787.00 byte/s) 2023-04-22 19:17:30.150 T:27995003 warning : AddOnLog: inputstream.adaptive: Segment download failed, attempt 5... 2023-04-22 19:17:30.150 T:27995003 debug : CurlFile::Open - 2023-04-22 19:17:30.491 T:27994909 debug : AddOnLog: inputstream.adaptive: SetFragmentDuration: Scale fragment duration: fdur:6000000, rep-scale:1000000, mov-scale:1000000 2023-04-22 19:17:30.491 T:27994909 debug : AddOnLog: inputstream.adaptive: SetFragmentDuration: Insert live segment: pts: 66000000 range_end: 280369374 2023-04-22 19:17:30.492 T:27994909 debug : AddOnLog: inputstream.adaptive: SetFragmentDuration: Scale fragment duration: fdur:287744, rep-scale:1000000, mov-scale:48000 2023-04-22 19:17:30.492 T:27994909 debug : AddOnLog: inputstream.adaptive: SetFragmentDuration: Insert live segment: pts: 65994666 range_end: 280369374 2023-04-22 19:17:30.916 T:27995003 debug : AddOnLog: inputstream.adaptive: Download finished: https://dice-live-us.akamaized.net/exp=1682302641~acl=%2f*~id=8468146e-f000-4a29-ba1d-61f723094ef9~data=hdntl~hmac=6f69e254d655cd644c1710c6b5e540ae4f8b9d7e2c4a7cc06dec21a17de79986/dash/live/2001830/237410-240341/exchange237410mlmpq_237410_6000/hRQvnNNlA/media_video_6000-280369373.m4s (downloaded 4233519 byte, speed 5541255.00 byte/s) 2023-04-22 19:17:32.027 T:27994321 debug : ProcessMouse: trying mouse action leftclick 2023-04-22 19:17:32.027 T:27994321 info : CVideoPlayer::CloseFile() 2023-04-22 19:17:32.027 T:27994321 debug : DeleteRenderer - deleting renderer 2023-04-22 19:17:32.027 T:27994909 info : CVideoPlayer::OnExit() 2023-04-22 19:17:32.027 T:27994321 debug : LinuxRendererGL: Cleaning up GL resources 2023-04-22 19:17:32.027 T:27994909 info : Closing stream player 1 2023-04-22 19:17:32.027 T:27994321 info : VideoPlayer: waiting for threads to exit 2023-04-22 19:17:32.027 T:27994909 debug : AddOnLog: inputstream.adaptive: EnableStream(1002: false) 2023-04-22 19:17:32.027 T:27994909 info : Waiting for audio thread to exit 2023-04-22 19:17:32.042 T:27995023 info : thread end: CVideoPlayerAudio::OnExit() 2023-04-22 19:17:32.042 T:27995023 debug : Thread VideoPlayerAudio 0x700010938000 terminating 2023-04-22 19:17:32.043 T:27994909 info : Closing audio device 2023-04-22 19:17:32.053 T:27994909 debug : CDVDAudio::Flush - flush audio stream 2023-04-22 19:17:32.053 T:27994383 debug : CActiveAE::DiscardStream - audio stream deleted 2023-04-22 19:17:32.053 T:27994383 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-04-22 19:17:32.054 T:27994384 info : Skipped 2 duplicate messages.. 2023-04-22 19:17:32.054 T:27994384 info : CActiveAESink::OpenSink - initialize sink 2023-04-22 19:17:32.054 T:27994909 info : Deleting audio codec 2023-04-22 19:17:32.054 T:27994909 info : Closing stream player 2 2023-04-22 19:17:32.054 T:27994909 debug : AddOnLog: inputstream.adaptive: EnableStream(1001: false) 2023-04-22 19:17:32.055 T:27994909 info : waiting for video thread to exit 2023-04-22 19:17:32.077 T:27995007 warning : OutputPicture - timeout waiting for buffer 2023-04-22 19:17:32.077 T:27995007 info : thread end: video_thread 2023-04-22 19:17:32.077 T:27995007 debug : Thread VideoPlayerVideo 0x700010832000 terminating 2023-04-22 19:17:32.078 T:27994909 info : deleting video codec 2023-04-22 19:17:32.079 T:27994909 debug : AddOnLog: inputstream.adaptive: Close() 2023-04-22 19:17:32.080 T:27994909 debug : AddOnLog: inputstream.adaptive: CSession::~CSession() 2023-04-22 19:17:32.080 T:27994909 debug : AddOnLog: inputstream.adaptive: CloseSessionId: Closing widevine session ID: C3E04E579641D7617E4548BBC6C13F53 2023-04-22 19:17:32.082 T:27994463 info : Deleting settings information for files /Volumes/tmm1-ssd/kodi-videos/mint.m3u 2023-04-22 19:17:32.082 T:27994463 debug : DoWork - Saving file state for video item /Volumes/tmm1-ssd/kodi-videos/mint.m3u 2023-04-22 19:17:32.083 T:27994909 debug : AddOnLog: inputstream.adaptive: CDMMessage: 4 arrived! 2023-04-22 19:17:32.084 T:27994909 debug : AddOnLog: inputstream.adaptive: CloseSessionId: Widevine session ID C3E04E579641D7617E4548BBC6C13F53 closed 2023-04-22 19:17:32.084 T:27994909 debug : AddOnLog: inputstream.adaptive: CloseSessionId: Closing widevine session ID: 62DC9D0FB3F4AD0EC56B6B1C2D98EEB6 2023-04-22 19:17:32.084 T:27994463 debug : DoWork - Marking video item /Volumes/tmm1-ssd/kodi-videos/mint.m3u as watched 2023-04-22 19:17:32.086 T:27994909 debug : AddOnLog: inputstream.adaptive: CDMMessage: 4 arrived! 2023-04-22 19:17:32.087 T:27994909 debug : AddOnLog: inputstream.adaptive: CloseSessionId: Widevine session ID 62DC9D0FB3F4AD0EC56B6B1C2D98EEB6 closed 2023-04-22 19:17:32.087 T:27994909 debug : SECTION:UnloadDll(/Users/tmm1/Library/Application Support/Kodi/addons/inputstream.adaptive/inputstream.adaptive.21.1.0.dylib) 2023-04-22 19:17:32.087 T:27994909 info : ADDON: Dll Destroyed - InputStream Adaptive 2023-04-22 19:17:32.087 T:27994909 debug : Thread VideoPlayer 0x7000106a9000 terminating 2023-04-22 19:17:32.087 T:27994321 info : VideoPlayer: finished waiting 2023-04-22 19:17:32.088 T:27994321 info : CVideoPlayer::CloseFile() 2023-04-22 19:17:32.088 T:27994321 info : VideoPlayer: waiting for threads to exit 2023-04-22 19:17:32.090 T:27994321 info : VideoPlayer: finished waiting 2023-04-22 19:17:32.092 T:27994321 debug : Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 2023-04-22 19:17:32.092 T:27994321 debug : Audio ID3 tag processor - delete ~CVideoPlayerAudioID3 2023-04-22 19:17:32.092 T:27994586 debug : OnPlayBackStopped: CApplication::OnPlayBackStopped 2023-04-22 19:17:32.096 T:27995725 warning : DARWINOSX: underflow (1936 vs 4096 bytes) 2023-04-22 19:17:32.103 T:27994321 debug : CGUIWindowManager::PreviousWindow: Deactivate 2023-04-22 19:17:32.103 T:27994321 debug : ------ Window Deinit (VideoOSD.xml) ------ 2023-04-22 19:17:32.104 T:27994321 debug : ------ Window Deinit (VideoFullScreen.xml) ------ 2023-04-22 19:17:32.109 T:27994321 debug : CGUIWindowManager::PreviousWindow: Activate new 2023-04-22 19:17:32.110 T:27994321 debug : ------ Window Init (MyVideoNav.xml) ------ 2023-04-22 19:17:32.116 T:27994321 debug : Window MyVideoNav.xml was already loaded 2023-04-22 19:17:32.116 T:27994321 debug : Alloc resources: 6.19 ms 2023-04-22 19:17:32.116 T:27994321 debug : CGUIMediaWindow::GetDirectory (/Volumes/tmm1-ssd/kodi-videos/) 2023-04-22 19:17:32.116 T:27994321 debug : ParentPath = [/Volumes/tmm1-ssd/kodi-videos/] 2023-04-22 19:17:32.121 T:27996465 debug : Thread BackgroundLoader start, auto delete: false 2023-04-22 19:17:32.121 T:27994321 debug : [threads] setting priority is not supported on this platform 2023-04-22 19:17:32.130 T:27996465 debug : Thread BackgroundLoader 0x70000e5c3000 terminating 2023-04-22 19:17:32.132 T:27994321 debug : ffmpeg[0x0]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format. 2023-04-22 19:17:32.204 T:27994321 info : Skipped 1 duplicate messages.. 2023-04-22 19:17:32.204 T:27994321 debug : ------ Window Deinit (DialogSeekBar.xml) ------ 2023-04-22 19:17:32.205 T:27994384 debug : CCoreAudioStream::Close: Restoring original physical format for stream 0x53. ([lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz)) 2023-04-22 19:17:32.310 T:27994321 debug : Uninhibiting OS screen saver 2023-04-22 19:17:32.344 T:27994321 debug : ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 2023-04-22 19:17:32.661 T:27994384 debug : CCoreAudioStream::SetPhysicalFormat: Physical format for stream 0x53. now active ([lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz)) 2023-04-22 19:17:32.661 T:27994384 debug : CCoreAudioStream::Close: Closed stream 0x53. 2023-04-22 19:17:32.661 T:27994384 debug : CActiveAESink::OpenSink - trying to open device DARWINOSX:default 2023-04-22 19:17:32.661 T:27994384 info : virtual bool CAESinkDARWINOSX::Initialize(AEAudioFormat &, std::string &): Opening default device Built-in Output 2023-04-22 19:17:32.662 T:27994384 debug : FindSuitableFormatForStream: Finding stream for format AE_FMT_FLOAT 2023-04-22 19:17:32.662 T:27994384 debug : FindSuitableFormatForStream: Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (96000Hz) rated 929.129272 2023-04-22 19:17:32.662 T:27994384 debug : FindSuitableFormatForStream: Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (48000Hz) rated 1419.564697 2023-04-22 19:17:32.662 T:27994384 debug : FindSuitableFormatForStream: Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz) rated 1508.000000 2023-04-22 19:17:32.663 T:27994384 debug : Initialize: Selected stream[0] - id: 0X53, Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz) 2023-04-22 19:17:32.663 T:27994384 debug : CCoreAudioDevice::SupportsMixing: Device mixing support : 'No'. 2023-04-22 19:17:32.663 T:27994384 debug : CCoreAudioStream::Open: Opened stream 0x53. 2023-04-22 19:17:32.663 T:27994384 debug : Initialize: Previous Virtual Format: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (44100Hz) 2023-04-22 19:17:32.663 T:27994384 debug : Initialize: Previous Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz) 2023-04-22 19:17:33.006 T:27994384 debug : CCoreAudioStream::SetPhysicalFormat: Physical format for stream 0x53. now active ([lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz)) 2023-04-22 19:17:33.013 T:27994384 debug : Initialize: New Virtual Format: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (44100Hz) 2023-04-22 19:17:33.014 T:27994384 debug : Initialize: New Physical Format: [lpcm] Mixable Interleaved 2 Channel 24-bit Signed Integer LE (44100Hz) 2023-04-22 19:17:33.014 T:27994384 debug : GetAEChannelMap Engine requests layout FL, FR 2023-04-22 19:17:33.014 T:27994384 debug : GetAEChannelMap trying to map to stereo layout: [Left] [Right] 2023-04-22 19:17:33.014 T:27994384 debug : GetAEChannelMap mapped channels to layout FL, FR 2023-04-22 19:17:33.015 T:27994384 debug : Initialize: using buffer size: 16384 (0.046440 ms) 2023-04-22 19:17:33.050 T:27994384 debug : CActiveAESink::OpenSink - DARWINOSX Initialized: 2023-04-22 19:17:33.050 T:27994384 debug : Output Device : Default 2023-04-22 19:17:33.050 T:27994384 debug : Sample Rate : 44100 2023-04-22 19:17:33.050 T:27994384 debug : Sample Format : AE_FMT_FLOAT 2023-04-22 19:17:33.050 T:27994384 debug : Channel Count : 2 2023-04-22 19:17:33.050 T:27994384 debug : Channel Layout: FL, FR 2023-04-22 19:17:33.050 T:27994384 debug : Frames : 512 2023-04-22 19:17:33.050 T:27994384 debug : Frame Size : 8 2023-04-22 19:17:33.051 T:27994383 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-04-22 19:17:36.095 T:27994539 info : Skipped 1 duplicate messages.. 2023-04-22 19:17:36.095 T:27994539 info : PVR Manager: Starting cleanup of cached images. 2023-04-22 19:17:36.611 T:27994539 info : PVR Manager: Cleaned up 0 cached images. 2023-04-22 19:17:38.402 T:27994321 debug : ------ Window Deinit (Pointer.xml) ------ ```
CastagnaIT commented 1 year ago

next time dont paste logs as flat text to the GH messages/description attach it directly as txt file or use kodi pastebin https://paste.kodi.tv/

tmm1 commented 1 year ago

2023-04-22 19:05:20.248 T:27954634 info : Process - eof reading from demuxer

The new parser seems to work the same as before, so this is some subtle regression in the layer between parser and demuxer

CastagnaIT commented 1 year ago

some changes has been done for live contents if you have chance please try test your stream with current ISA Omega branch you can use this test build https://jenkins.kodi.tv/blue/organizations/jenkins/xbmc%2Finputstream.adaptive/detail/PR-1312/1/artifacts also provide a new debug log if problem still occurs