pigskin / kodi-gamepass

NFL Game Pass add-on for Kodi
Other
123 stars 83 forks source link

Can't load games or NFL network #400

Closed ctcoulter closed 2 years ago

ctcoulter commented 6 years ago

I'm having an issue with the same symptoms as #396 , but the error is different so I figured I'd post a new issue rather than distract from the other issue.

I'm not able to get any kind of streaming working. I'm on Kodi 17.3 and have enabled Adaptive InputStream. I can get prompted for the bitrate, but once I select it, nothing happens.

I'm not sure what the "dialog not created" exceptions mean really, but it looks like creating the demuxer is likely the actual problem.

Logs:

10:02:46.885 T:18446744072501378232  NOTICE: Trying to open: 44100 samplerate 12 channelMask 4 encoding
10:02:46.895 T:18446744072501378232  NOTICE: CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 45152 m_frames: 2822 m_frameSize: 8 channels: 2
10:07:34.104 T:18446744072501378232  NOTICE: Trying to open: 44100 samplerate 12 channelMask 4 encoding
10:07:34.112 T:18446744072501378232  NOTICE: CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 45152 m_frames: 2822 m_frameSize: 8 channels: 2
10:07:58.914 T:18446744072531893728   ERROR: EXCEPTION: Dialog not created.
10:08:01.066 T:18446744072531893728 WARNING: CSkinInfo: failed to load skin settings
10:08:01.930 T:18446744072531893728   ERROR: EXCEPTION: Dialog not created.
10:08:19.223 T:18446744072499596904  NOTICE: VideoPlayer: Opening: https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1536505324~exp=1536505504~acl=/*~hmac=ed186b915cdc304eaa06c481acddc98724ca77c5832cd710bead57e83e4a5415&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox|Connection=keep-alive&User-Agent=Firefox
10:08:19.223 T:18446744072499596904 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
10:08:19.347 T:18446744072548641904  NOTICE: Creating InputStream
10:08:19.999 T:18446744072548641904  NOTICE: Creating Demuxer
10:08:50.358 T:18446744072548641904   ERROR: OpenDemuxStream - Error creating demuxer
10:08:50.358 T:18446744072548641904  NOTICE: CVideoPlayer::OnExit()
10:08:50.440 T:18446744072499596904  NOTICE: CVideoPlayer::CloseFile()
10:08:50.440 T:18446744072499596904  NOTICE: VideoPlayer: waiting for threads to exit
10:08:50.440 T:18446744072499596904  NOTICE: VideoPlayer: finished waiting
10:08:50.441 T:18446744072499596904  NOTICE: CVideoPlayer::CloseFile()
10:08:50.441 T:18446744072499596904  NOTICE: VideoPlayer: waiting for threads to exit
10:08:50.441 T:18446744072499596904  NOTICE: VideoPlayer: finished waiting

Any help would be greatly appreciated! Thanks.

UPDATE: am able to view condensed/highlights, but not full games. i captured logs again with debug turned on:

10:53:58.026 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0
10:53:58.026 T:18446744072501181280   DEBUG: OnKey: return (0xf00d) pressed, action is Select
10:53:58.026 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0
10:53:58.027 T:18446744072502965448    INFO: CActiveAESink::OpenSink - initialize sink
10:53:58.027 T:18446744072502965448   DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack
10:53:58.027 T:18446744072502965448   DEBUG: CAESinkAUDIOTRACK::Initialize requested: sampleRate 44100; format: AE_FMT_FLOAT; channels: 2
10:53:58.027 T:18446744072502965448   DEBUG: Updated SampleRate: 32000 Distance: 108900
10:53:58.027 T:18446744072502965448   DEBUG: Updated SampleRate: 44100 Distance: 0
10:53:58.027 T:18446744072502965448  NOTICE: Trying to open: 44100 samplerate 12 channelMask 4 encoding
10:53:58.028 T:18446744072502965448   DEBUG: Minimum size we need for stream: 22576
10:53:58.028 T:18446744072502965448   DEBUG: Created Audiotrackbuffer with playing time of 127.981859 ms min buffer size: 45152 bytes
10:53:58.033 T:18446744072502965448  NOTICE: CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 45152 m_frames: 2822 m_frameSize: 8 channels: 2
10:53:58.033 T:18446744072502965448   DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized:
10:53:58.033 T:18446744072502965448   DEBUG:   Output Device : android
10:53:58.033 T:18446744072502965448   DEBUG:   Sample Rate   : 44100
10:53:58.033 T:18446744072502965448   DEBUG:   Sample Format : AE_FMT_FLOAT
10:53:58.034 T:18446744072502965448   DEBUG:   Channel Count : 2
10:53:58.034 T:18446744072502965448   DEBUG:   Channel Layout: FL,FR
10:53:58.034 T:18446744072502965448   DEBUG:   Frames        : 2822
10:53:58.034 T:18446744072502965448   DEBUG:   Frame Size    : 8
10:53:58.044 T:18446744072501181280   DEBUG: ------ Window Init (DialogBusy.xml) ------
10:53:58.050 T:18446744072528581368   DEBUG: Thread JobWorker start, auto delete: true
10:53:58.223 T:18446744072501181280   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
10:53:58.237 T:18446744072501181280   DEBUG: ------ Window Init (DialogSelect.xml) ------
10:53:58.238 T:18446744072501181280   DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'noop'
10:54:00.662 T:18446744072501181280   DEBUG: Previous line repeats 3 times.
10:54:00.662 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
10:54:00.662 T:18446744072501181280   DEBUG: OnKey: down (0xf081) pressed, action is Down
10:54:00.803 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
10:54:00.853 T:18446744072501181280   DEBUG: Previous line repeats 1 times.
10:54:00.853 T:18446744072501181280   DEBUG: OnKey: down (0xf081) pressed, action is Down
10:54:00.945 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
10:54:01.457 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0
10:54:01.598 T:18446744072501181280   DEBUG: OnKey: return (0xf00d) pressed, action is Select
10:54:01.599 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0
10:54:01.811 T:18446744072501181280   DEBUG: ------ Window Deinit (DialogSelect.xml) ------
10:54:03.380 T:18446744072501181280   DEBUG: ------ Window Init (DialogSelect.xml) ------
10:54:03.381 T:18446744072501181280   DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'noop'
10:54:04.691 T:18446744072501181280   DEBUG: Previous line repeats 3 times.
10:54:04.691 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
10:54:04.691 T:18446744072501181280   DEBUG: OnKey: down (0xf081) pressed, action is Down
10:54:04.829 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
10:54:06.634 T:18446744072501181280   DEBUG: Previous line repeats 1 times.
10:54:06.634 T:18446744072501181280   DEBUG: OnKey: down (0xf081) pressed, action is Down
10:54:06.780 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
10:54:07.017 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0
10:54:07.200 T:18446744072501181280   DEBUG: OnKey: return (0xf00d) pressed, action is Select
10:54:07.201 T:18446744072501181280   DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0
10:54:07.412 T:18446744072501181280   DEBUG: ------ Window Deinit (DialogSelect.xml) ------
10:54:07.416 T:18446744072501181280   DEBUG: CurlFile::ParseAndCorrectUrl() adding custom header option 'Connection: keep-alive'
10:54:07.417 T:18446744072501181280    INFO: easy_aquire - Created session to https://vodus-i-video-nfl.akamaized.net
10:54:08.214 T:18446744072501181280   DEBUG: CCurlFile::GetMimeType - https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1536508071~exp=1536508251~acl=/*~hmac=dbdba76bfd2c3760e55a70a034463ea898f95b05847a8f8cc7462bce59aa5c7d&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox|Connection=keep-alive&User-Agent=Firefox -> application/vnd.apple.mpegurl
10:54:08.259 T:18446744072501181280   DEBUG: Loading settings for https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1536508071~exp=1536508251~acl=/*~hmac=dbdba76bfd2c3760e55a70a034463ea898f95b05847a8f8cc7462bce59aa5c7d&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox|Connection=keep-alive&User-Agent=Firefox
10:54:08.265 T:18446744072501181280   DEBUG: CPlayerCoreFactory::GetPlayers(https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1536508071~exp=1536508251~acl=/*~hmac=dbdba76bfd2c3760e55a70a034463ea898f95b05847a8f8cc7462bce59aa5c7d&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox|Connection=keep-alive&User-Agent=Firefox)
10:54:08.265 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
10:54:08.265 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
10:54:08.266 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
10:54:08.266 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
10:54:08.266 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
10:54:08.267 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
10:54:08.267 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
10:54:08.268 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
10:54:08.268 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
10:54:08.268 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
10:54:08.269 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
10:54:08.269 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
10:54:08.269 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
10:54:08.270 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
10:54:08.270 T:18446744072501181280   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
10:54:08.271 T:18446744072501181280   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
10:54:08.271 T:18446744072501181280   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
10:54:08.271 T:18446744072501181280   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
10:54:08.272 T:18446744072501181280   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
10:54:08.272 T:18446744072501181280   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
10:54:08.280 T:18446744072501181280   DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
10:54:08.280 T:18446744072501181280  NOTICE: VideoPlayer: Opening: https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1536508071~exp=1536508251~acl=/*~hmac=dbdba76bfd2c3760e55a70a034463ea898f95b05847a8f8cc7462bce59aa5c7d&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox|Connection=keep-alive&User-Agent=Firefox
10:54:08.281 T:18446744072501181280 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
10:54:08.282 T:18446744072501181280   DEBUG: CurlFile::ParseAndCorrectUrl() adding custom header option 'Connection: keep-alive'
10:54:08.401 T:18446744072501181280   DEBUG: CCurlFile::GetMimeType - https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1536508071~exp=1536508251~acl=/*~hmac=dbdba76bfd2c3760e55a70a034463ea898f95b05847a8f8cc7462bce59aa5c7d&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox|Connection=keep-alive&User-Agent=Firefox -> application/vnd.apple.mpegurl
10:54:08.402 T:18446744072501181280   DEBUG: LinuxRendererGL: Cleaning up GL resources
10:54:08.403 T:18446744072522714096   DEBUG: Thread VideoPlayer start, auto delete: false
10:54:08.408 T:18446744072522714096  NOTICE: Creating InputStream
10:54:08.411 T:18446744072522714096   DEBUG: CurlFile::ParseAndCorrectUrl() adding custom header option 'Connection: keep-alive'
10:54:08.412 T:18446744072522714096   DEBUG: CurlFile::Open(0x9a0cea40) https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1536508071~exp=1536508251~acl=/*~hmac=dbdba76bfd2c3760e55a70a034463ea898f95b05847a8f8cc7462bce59aa5c7d&hdcore=2.11.3
10:54:08.903 T:18446744072501181280   DEBUG: ------ Window Init (DialogBusy.xml) ------
10:54:08.905 T:18446744072501181280   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
10:54:09.057 T:18446744072522714096  NOTICE: Creating Demuxer
10:54:09.058 T:18446744072522714096   DEBUG: CDVDDemuxFFmpeg::GetFFMpegOptionsFromInput() adding custom header option 'Connection: keep-alive'
10:54:09.058 T:18446744072522714096   DEBUG: CDVDDemuxFFmpeg::GetFFMpegOptionsFromInput() adding ffmpeg option 'user-agent: Firefox'
10:54:28.066 T:18446744072528581368   DEBUG: Thread JobWorker 18446744072528581368 terminating (autodelete)
10:54:39.155 T:18446744072522714096   ERROR: ffmpeg[B9424BF0]: [tls] The TLS connection was non-properly terminated.
10:54:39.215 T:18446744072501181280    INFO: CheckIdle - Closing session to https://vodus-i-video-nfl.akamaized.net (easy=0xbbb567a8, multi=0xb9c469b0)
10:54:39.384 T:18446744072522714096   ERROR: ffmpeg[B9424BF0]: [hls,applehttp] Error when loading first segment 'https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Fragments(video=0,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128)'
10:54:39.410 T:18446744072522714096   DEBUG: Error, could not open file https://vodus-i-video-nfl.akamaized.net/vodus/e9323e31-b160-4bd0-b5f7-84587d9ea8b9/NoADs_ATL_PHI_1080_reg1.ism/QualityLevels(3443943)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1536508071~exp=1536508251~acl=/*~hmac=dbdba76bfd2c3760e55a70a034463ea898f95b05847a8f8cc7462bce59aa5c7d&hdcore=2.11.3
10:54:39.410 T:18446744072522714096   ERROR: OpenDemuxStream - Error creating demuxer
10:54:39.411 T:18446744072522714096  NOTICE: CVideoPlayer::OnExit()
10:54:39.411 T:18446744072522714096   DEBUG: OnPlayBackStopped: play state was 1, starting 1
10:54:39.411 T:18446744072522714096   DEBUG: Thread VideoPlayer 18446744072522714096 terminating
10:54:39.415 T:18446744072501181280   DEBUG: OnPlayBackStopped: play state was 3, starting 0
10:54:39.418 T:18446744072501193864   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
10:54:39.419 T:18446744072501193864   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
10:54:39.453 T:18446744072513270768   DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop  - data: {"end":false,"item":{"type":"unknown"}}
10:54:39.467 T:18446744072501181280  NOTICE: CVideoPlayer::CloseFile()
10:54:39.468 T:18446744072501181280  NOTICE: VideoPlayer: waiting for threads to exit
10:54:39.468 T:18446744072501181280  NOTICE: VideoPlayer: finished waiting
10:54:39.468 T:18446744072501181280   DEBUG: DeleteRenderer - deleting renderer
10:54:39.469 T:18446744072501181280   DEBUG: LinuxRendererGL: Cleaning up GL resources
10:54:39.469 T:18446744072501181280  NOTICE: CVideoPlayer::CloseFile()
10:54:39.469 T:18446744072501181280  NOTICE: VideoPlayer: waiting for threads to exit
10:54:39.469 T:18446744072501181280  NOTICE: VideoPlayer: finished waiting
10:54:39.477 T:18446744072501181280   DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
10:54:39.487 T:18446744072513241856   DEBUG: [plugin.video.youtube] Cleared home window properties: []
10:54:39.754 T:18446744072555750304   DEBUG: Thread JobWorker start, auto delete: true
ctcoulter commented 6 years ago

Should also mention that this is on a first generation FireTV if that makes a difference.

EDIT: and I updated to the latest release (2018.09.08)

ctcoulter commented 6 years ago

I'm thinking this might actually be the same issue as reported in the other that I referenced above after looking at the debug logs.

One thing I noticed that might be an issue is that the "Adaptive InputStream" option seems to be unchecked almost every time I go back to the settings in the app. It is enabled in the app settings in Kodi. I'm not entirely confident it's actually being applied after I click OK in the settings.

aqw commented 6 years ago

Hey @ctcoulter Yeah, the code around the Adaptive Streaming is a bit... unfortunate. Our addon silently re-disables Adaptive Streaming if it detects that there's no support for it. We /really/ should be showing a dialog (or simply grey-ing out the option if no support is detected). Sorry about the subpar experience there.

I don't know off-hand which versions of Kodi ship with Adaptive Streaming. It could be that you need it. Otherwise... it's not terribly obvious from the logs what's going on. You say that you can watch condensed but not full games. Is this for live games or past games?

---Alex

ctcoulter commented 6 years ago

Able to watch: Past games (preseason and the ATL/PHI game from this past thursday) in highlight or condensed mode Video for live games the hour before they start (FOX, etc)

Unable to watch: "Game video" for past games (which I assume is the same as 'full') Live games NFL Network Live

Since I posted this, the 1pm (EST) games had gotten close enough where I could launch them and see the pre-game analysis shows. Was able to launch video for upcoming games. But when I tried to open them within a few minutes of them starting it went back to being unable to launch again.

Regarding the adaptive streaming - should I upgrade to a newer version of Kodi? I'm on a FireTV and sideload, so I don't usually update very often. It looks like Kodi 18 is not supported by this plugin yet though based on other open issues?

aqw commented 6 years ago

@ctcoulter Thanks for the info. Sounds like an interesting mix of problems...

Unfortunately, I'm on the road right now, so I can't really fire up my own Kodi to test and dig into things. Maybe someone else with access will be able to help.

You're correct that Kodi 18 isn't yet an option, but I am actively working towards it. Things are a bit unstable right now, with the (as always) early-season-breakage that Game Pass does, and the changes I'm making to the addon to pay down some of the technical debt we've accrued over the years.

No promises, but I'm hoping to have a Kodi 18 version ready by week 2 or 3.

Thanks for taking the time to file a bug report. Keep 'em coming, and hopefully someone (who isn't on the road) can jump on and help out. :-)

---Alex

ctcoulter commented 6 years ago

No problem. Thanks for your help! I am actually thinking I may found something to try and am working on that now. Hopefully it fixes it. Apparently Kodi 17.3 doesn't enable the adaptive inputstream by default. Will give this a shot in a short while and see. fingers crossed

For reference: http://www.best-kodi-guides.eu/kodi-add-on-reviews/enable-inputstream-adaptive-kodi-krypton/

attilabukor commented 6 years ago

I'm running into the same issue on 17.6 on Raspberry Pi. I guess it doesn't make a difference, just thought I'd mention it.

ctcoulter commented 6 years ago

Well, unfortunately enabling InputStream actually made things worse, although it may have helped shed some light on things with new logs. I'm not able to do condensed or highlights.

These lines specifically seem to be the issue now (full logs below after):

12:35:59.461 T:18446744072553703544   DEBUG: AddOnLog: InputStream Adaptive: found inputstream.adaptive.manifest_type: hls
12:35:59.461 T:18446744072553703544   ERROR: AddOnLog: InputStream Adaptive: Invalid / not given inputstream.adaptive.manifest_type
12:35:56.940 T:18446744072489445408   DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0
12:35:56.941 T:18446744072489445408   DEBUG: OnKey: return (0xf00d) pressed, action is Select
12:35:56.941 T:18446744072489445408   DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0
12:35:56.961 T:18446744072489445408   DEBUG: ------ Window Init (DialogBusy.xml) ------
12:35:56.978 T:18446744072511760296   DEBUG: Thread JobWorker start, auto delete: true
12:35:58.263 T:18446744072489445408   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
12:35:58.293 T:18446744072489445408   DEBUG: CurlFile::ParseAndCorrectUrl() adding custom header option 'Connection: keep-alive'
12:35:58.293 T:18446744072489445408    INFO: easy_aquire - Created session to https://livemain-i-video-nfl.akamaized.net
12:35:59.224 T:18446744072489445408   DEBUG: CCurlFile::GetMimeType - https://livemain-i-video-nfl.akamaized.net/ch09/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91.ism/manifest(format=m3u8-aapl-v3,filter=chromecast-live)?hdnea=st=1536514467~exp=1536514647~acl=/*~hmac=d24c36fba983caec8672912b39199da94ee91833beca8259567a576069a0a992&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox -> application/vnd.apple.mpegurl
12:35:59.285 T:18446744072489445408   DEBUG: Loading settings for https://livemain-i-video-nfl.akamaized.net/ch09/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91.ism/manifest(format=m3u8-aapl-v3,filter=chromecast-live)?hdnea=st=1536514467~exp=1536514647~acl=/*~hmac=d24c36fba983caec8672912b39199da94ee91833beca8259567a576069a0a992&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox
12:35:59.291 T:18446744072489445408   DEBUG: CPlayerCoreFactory::GetPlayers(https://livemain-i-video-nfl.akamaized.net/ch09/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91.ism/manifest(format=m3u8-aapl-v3,filter=chromecast-live)?hdnea=st=1536514467~exp=1536514647~acl=/*~hmac=d24c36fba983caec8672912b39199da94ee91833beca8259567a576069a0a992&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox)
12:35:59.291 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:35:59.291 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:35:59.292 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
12:35:59.292 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:35:59.292 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
12:35:59.293 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:35:59.293 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:35:59.294 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
12:35:59.294 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
12:35:59.294 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
12:35:59.295 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:35:59.295 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:35:59.295 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:35:59.296 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:35:59.296 T:18446744072489445408   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
12:35:59.296 T:18446744072489445408   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:35:59.297 T:18446744072489445408   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
12:35:59.297 T:18446744072489445408   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
12:35:59.297 T:18446744072489445408   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:35:59.298 T:18446744072489445408   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
12:35:59.305 T:18446744072489445408   DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
12:35:59.305 T:18446744072489445408  NOTICE: VideoPlayer: Opening: https://livemain-i-video-nfl.akamaized.net/ch09/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91.ism/manifest(format=m3u8-aapl-v3,filter=chromecast-live)?hdnea=st=1536514467~exp=1536514647~acl=/*~hmac=d24c36fba983caec8672912b39199da94ee91833beca8259567a576069a0a992&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox
12:35:59.305 T:18446744072489445408 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
12:35:59.306 T:18446744072489445408   DEBUG: CurlFile::ParseAndCorrectUrl() adding custom header option 'Connection: keep-alive'
12:35:59.428 T:18446744072489445408   DEBUG: CCurlFile::GetMimeType - https://livemain-i-video-nfl.akamaized.net/ch09/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91.ism/manifest(format=m3u8-aapl-v3,filter=chromecast-live)?hdnea=st=1536514467~exp=1536514647~acl=/*~hmac=d24c36fba983caec8672912b39199da94ee91833beca8259567a576069a0a992&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox -> application/vnd.apple.mpegurl
12:35:59.428 T:18446744072489445408   DEBUG: LinuxRendererGL: Cleaning up GL resources
12:35:59.430 T:18446744072553703544   DEBUG: Thread VideoPlayer start, auto delete: false
12:35:59.434 T:18446744072553703544  NOTICE: Creating InputStream
12:35:59.436 T:18446744072553703544   DEBUG: AddOnLog: InputStream Adaptive: ADDON_Destroy()
12:35:59.438 T:18446744072553703544   DEBUG: SECTION:UnloadDll(/data/app/org.xbmc.kodi-1/lib/arm/libinputstream.adaptive.so)
12:35:59.438 T:18446744072553703544    INFO: ADDON: Dll Destroyed - InputStream Adaptive
12:35:59.439 T:18446744072553703544   DEBUG: ADDON: Dll Initializing - InputStream Adaptive
12:35:59.440 T:18446744072553703544   DEBUG: SECTION:LoadDLL(/data/app/org.xbmc.kodi-1/lib/arm/libinputstream.adaptive.so)
12:35:59.440 T:18446744072553703544   DEBUG: Loading: /data/app/org.xbmc.kodi-1/lib/arm/libinputstream.adaptive.so
12:35:59.452 T:18446744072553703544   DEBUG: AddOnLog: InputStream Adaptive: libXBMC_addon successfully loaded
12:35:59.458 T:18446744072553703544   DEBUG: AddOnLog: InputStream Adaptive: ADDON_Create()
12:35:59.459 T:18446744072553703544    INFO: AddOnLog: InputStream Adaptive: SetVideoResolution (1920 x 1080)
12:35:59.460 T:18446744072553703544   DEBUG: AddOnLog: InputStream Adaptive: Open()
12:35:59.461 T:18446744072553703544   DEBUG: AddOnLog: InputStream Adaptive: found inputstream.adaptive.manifest_type: hls
12:35:59.461 T:18446744072553703544   ERROR: AddOnLog: InputStream Adaptive: Invalid / not given inputstream.adaptive.manifest_type
12:35:59.462 T:18446744072553703544   DEBUG: AddOnLog: InputStream Adaptive: GetStreamIds()
12:35:59.462 T:18446744072553703544   ERROR: CVideoPlayer::OpenInputStream - error opening [https://livemain-i-video-nfl.akamaized.net/ch09/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91.ism/manifest(format=m3u8-aapl-v3,filter=chromecast-live)?hdnea=st=1536514467~exp=1536514647~acl=/*~hmac=d24c36fba983caec8672912b39199da94ee91833beca8259567a576069a0a992&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox]
12:35:59.463 T:18446744072553703544  NOTICE: CVideoPlayer::OnExit()
12:35:59.463 T:18446744072553703544   DEBUG: AddOnLog: InputStream Adaptive: Close()
12:35:59.464 T:18446744072553703544    INFO: ADDON: Dll Stopped - InputStream Adaptive
12:35:59.465 T:18446744072553703544   DEBUG: OnPlayBackStopped: play state was 1, starting 1
12:35:59.465 T:18446744072489445408   DEBUG: OnPlayBackStopped: play state was 3, starting 0
12:35:59.467 T:18446744072553703544   DEBUG: Thread VideoPlayer 18446744072553703544 terminating
12:35:59.471 T:18446744072489458848   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
12:35:59.473 T:18446744072489458848   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
12:35:59.502 T:18446744072501858152   DEBUG: [plugin.video.youtube] Cleared home window properties: []
12:35:59.502 T:18446744072489445408  NOTICE: CVideoPlayer::CloseFile()
12:35:59.503 T:18446744072489445408  NOTICE: VideoPlayer: waiting for threads to exit
12:35:59.503 T:18446744072489445408  NOTICE: VideoPlayer: finished waiting
12:35:59.503 T:18446744072489445408   DEBUG: DeleteRenderer - deleting renderer
12:35:59.503 T:18446744072489445408   DEBUG: LinuxRendererGL: Cleaning up GL resources
12:35:59.503 T:18446744072489445408  NOTICE: CVideoPlayer::CloseFile()
12:35:59.503 T:18446744072489445408  NOTICE: VideoPlayer: waiting for threads to exit
12:35:59.503 T:18446744072489445408  NOTICE: VideoPlayer: finished waiting
12:35:59.507 T:18446744072489445408   DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
12:35:59.555 T:18446744072501844216   DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop  - data: {"end":false,"item":{"type":"unknown"}}
12:35:59.564 T:18446744072501835976   DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop  - data: {"end":false,"item":{"type":"unknown"}}
ctcoulter commented 6 years ago

To be honest, I don't understand streaming protocols, etc so much. But looking at the inputstream.adaptive plugin repo and the logs... maybe the manifest_type should be ism instead of hls? the URL it's trying to load has an .ism extension and that's one of the three types you can pass to the adaptive inputstream per their readme:

https://github.com/peak3d/inputstream.adaptive

create a .strm file / or addon with passes an url with sets inputstream.adaptive.manifest_type to either "mpd", "ism" or "hls" and open the strm file in kodi

the video being loaded with ISM extension:

12:35:59.305 T:18446744072489445408  NOTICE: VideoPlayer: Opening: https://livemain-i-video-nfl.akamaized.net/ch09/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91/5fc4bb1f-da88-4740-bf8f-6ecaf1f5ef91.ism/manifest(format=m3u8-aapl-v3,filter=chromecast-live)?hdnea=st=1536514467~exp=1536514647~acl=/*~hmac=d24c36fba983caec8672912b39199da94ee91833beca8259567a576069a0a992&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox
ctcoulter commented 6 years ago

Welp... for what it's worth, I was able to get it working it seems like by updating to Krypton 17.6.

What I tried first:

That led me to looking into issues related to that on inputstream.adaptive repo. Noticed that 17.3 was pinned to version 1.08 of that plugin and they were up to 2.0.20. Tried downloading and installing that on 17.3, but it wasn't a supported .zip format for installing the addin.

So I updated to 17.6 and reverted to the official 09.08.2018 from this repo. Was able to open up RedZone and a couple different live games without any issue.

May be a way to get it working on older versions, but it works now so I'm just going to settle for that and leave it be.

Sorry for wasting your time and spamming your issues page hah... hopefully this will help someone else!

aqw commented 5 years ago

@ctcoulter Glad to hear you got it working! Just to confirm, the addon worked for you with the 2018.09.08 version, yes?

Jesia commented 5 years ago

I have the same issue on the Raspberry Pi. I tried it with the last 2 versions but it didn'T work. NFL Network and Redzone worked fine, also the old games from the preseason

ctcoulter commented 5 years ago

@aqw It was working just fine until the second round of games came on (4pm EST) yesterday. Then I started having the same problem other people are reporting in issue #396 with the week 1 game list erroring out.

I am using the 2018.09.08 version, Krypton 17.6 and inputstream.adaptive 2.0.19 (2.0.20 is available, but not in the kodi repo yet).

I tried checking debug logs for what was causing the week 1 listing issue, but there was nothing helpful there at all. Didn't see any errors being reported.

ctcoulter commented 5 years ago

@Jesia I think ultimately what fixed the issue that you were experiencing (for me at least) was enabling the InputStream Adaptive plugin in Kodi, not just the plugin settings. I provided a link up above (http://www.best-kodi-guides.eu/kodi-add-on-reviews/enable-inputstream-adaptive-kodi-krypton/). Did you check that it was enabled?

Also, should check to make sure you're on a current version of inpustream.adaptive.

Jesia commented 5 years ago

@ctcoulter the InputStream is activated. but after taking a closer look i think that the issue is different, since the problem is that the games don't show up. And after i select Week 1 the whol thing crahes

attilabukor commented 5 years ago

So this is what still happens for me on Kodi 17.6 and GamePass plugin 2018.09.08. on Raspberry Pi/Raspbian, with and without inputStream Adaptive and RTMP Input.

19:27:19.478 T:1911074816 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:27:19.495 T:1421861632  NOTICE: Creating InputStream
19:27:19.709 T:1421861632  NOTICE: Creating Demuxer
19:27:49.824 T:1421861632   ERROR: OpenDemuxStream - Error creating demuxer
19:27:49.824 T:1421861632  NOTICE: CVideoPlayer::OnExit()
19:27:49.825 T:1911074816  NOTICE: CVideoPlayer::CloseFile()
19:27:49.825 T:1911074816  NOTICE: VideoPlayer: waiting for threads to exit
19:27:49.825 T:1911074816  NOTICE: VideoPlayer: finished waiting
19:27:49.826 T:1911074816  NOTICE: CVideoPlayer::CloseFile()
19:27:49.826 T:1911074816  NOTICE: VideoPlayer: waiting for threads to exit
19:27:49.826 T:1911074816  NOTICE: VideoPlayer: finished waiting

Any idea what it could be?

msmittag commented 5 years ago

Hi guys, sorry to say - same problem here. Anything worked well till yesterday. Now non of the stream will start (Unerwarteter Fehler ='(.) So I downloaded updates and am now on InputStream Adaptive 2.0.20.0 (definately activated) and Game Pass AddOn from 2018.09.08. Sorrowly no change. Any more ideas?

Cheers Michael

aqw commented 5 years ago

I just retested, and I can confirm that my latest code in the pigskin project also has this problem.

That means this isn't a simple fix by porting over some of the new code.

I am leaving for vacation tomorrow, and my SO has been pretty clear that I won't be anywhere near a computer during it. If this is still a problem in a week, I'll look at it when I'm back.

aqw commented 5 years ago

I take it back, I /cannot/ reproduce this problem. And I have verified that the latest auth code in pigskin /is/ present in xbmc-gamepass.

So... something else weird is going on.

tinzarian commented 5 years ago

Earlier todayI couldn't play any streams, now I can't even login. I'm gonna stick with the app and chromecast for the time being.

I absolutely appreciate all the work the devs are putting in to this, all in all it's just too unstable at the moment

kaileu commented 5 years ago

I think this is the problem: https://github.com/aqw/pigskin/pull/23 I Also think that the Gamepass Provider tests new Features on a few customers thats the reason my login auth didn't changed but yours and that some people start having problems a week earlier than others. Its really disgusting such an approach but .....

tinzarian commented 5 years ago

I agree, using your customers as unwitting beta(or maybe even alpha)-testers is not the right way to do business. On the plus side, I gave it another try with the pigskin#23 change, and everythng worked fine last night, even refreshing the game list.

wysiecki commented 5 years ago

Hi, got the same problem on raspPi 3, no live games and no full games, despite the fact, that the url's look quite the same. Version cloned from git

Not Working (full game): 12:40:10.225 T:1910779904 NOTICE: VideoPlayer: Opening: https://vodus-i-video-nfl.akamaized.net/vodus/6738e931-7996-4749-96a2-1fabd3808844/NoADs_GB_MIN_1080_reg12.ism/QualityLevels(4743723)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_128,filter=chromecast)?hdnea=st=1543232316~exp=1543232496~acl=/*~hmac=4cd479762571b7fc5a3e73e384870a7a742d2adb6887919c91deb5bc7685c099&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox|Connection=keep-alive&User-Agent=Firefox 12:40:10.225 T:1910779904 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 12:40:10.247 T:1537016576 NOTICE: Creating InputStream 12:40:10.512 T:1537016576 NOTICE: Creating Demuxer 12:40:40.836 T:1537016576 ERROR: OpenDemuxStream - Error creating demuxer 12:40:40.837 T:1537016576 NOTICE: CVideoPlayer::OnExit()

Working (compressed game) 12:41:16.522 T:1910779904 NOTICE: VideoPlayer: Opening: https://vodus-i-video-nfl.akamaized.net/vodus/567fbb42-abda-4fe6-9aca-292583b9ebfd/GI40_SEA_CAR_1080_reg12.ism/QualityLevels(5992141)/Manifest(video,format=m3u8-aapl-v3,audiotrack=aac_UND_2_127,filter=chromecast)?hdnea=st=1543232383~exp=1543232563~acl=/*~hmac=dd078bebf557df6d00bd39aecf5b5c5f410f525d8609a1e18164792522f7a112&hdcore=2.11.3|Connection=keep-alive&User-Agent=Firefox|Connection=keep-alive&User-Agent=Firefox 12:41:16.546 T:1588929280 NOTICE: Creating InputStream 12:41:16.692 T:1588929280 NOTICE: Creating Demuxer 12:41:18.211 T:1588929280 NOTICE: Opening stream: 0 source: 256 12:41:18.212 T:1588929280 NOTICE: Creating video codec with codec id: 28 12:41:18.219 T:1588929280 NOTICE: Creating video thread 12:41:18.219 T:1537016576 NOTICE: running thread: video_thread 12:41:18.219 T:1588929280 NOTICE: Opening stream: 1 source: 256 12:41:18.219 T:1588929280 NOTICE: Finding audio codec for: 86018 12:41:18.221 T:1588929280 NOTICE: Creating audio thread

Thanks, the plugin is great, love it

jm-duke commented 2 years ago

Doing some clean-up and this issue report is really old, so I'm closing it.