[wangsu]

After upgrade or new install Kodi 15 Isengard, all HDHR streams in upnp cannot play.

The log as follow:

09:34:58 T:3992 NOTICE: Creating InputStream
09:34:58 T:3992 INFO: XCURL:llLibCurlGlobal::easy_aquire - Created session to http://192.168.111.112
09:34:58 T:3992 DEBUG: CCurlFile::GetMimeType - http://192.168.111.112:5004/auto/v6?dlna -> video/mpeg
09:34:58 T:3992 DEBUG: CFileCache::Open - opening <auto/v6> using cache
09:34:58 T:3992 DEBUG: CurlFile::Open(0D3FCC70) http://192.168.111.112:5004/auto/v6?dlna
09:34:58 T:3992 ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 406
09:34:58 T:3992 ERROR: CCurlFile::Open failed with code 406 for http://192.168.111.112:5004/auto/v6?dlna
09:34:58 T:3992 ERROR: XFILE::CFileCache::Open - failed to open source <http://192.168.111.112:5004/auto/v6?dlna>
09:34:58 T:3992 ERROR: CDVDPlayer::OpenInputStream - error opening [http://192.168.111.112:5004/auto/v6?dlna]
09:34:58 T:3992 NOTICE: CDVDPlayer::OnExit()
09:34:58 T:3992 DEBUG: CApplication::OnPlayBackStopped: play state was 1, starting 1
09:34:58 T:3992 DEBUG: Thread DVDPlayer 3992 terminating
09:34:58 T:8080 DEBUG: CApplication::OnPlayBackStopped: play state was 3, starting 0
09:34:58 T:8080 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
09:34:58 T:8080 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
09:34:58 T:8080 ERROR: Playlist Player: skipping unplayable item: 0, path [upnp://DC11735B-E5E4-383E-BDD9-1A0C68BC6265/Channels%2fv6/]
09:34:58 T:8080 DEBUG: Playlist Player: one or more items failed to play... aborting playback
09:34:58 T:8080 DEBUG: ------ Window Init (DialogOK.xml) ------
09:34:58 T:8080 NOTICE: CDVDPlayer::CloseFile()
09:34:58 T:8080 NOTICE: DVDPlayer: waiting for threads to exit
09:34:58 T:8080 NOTICE: DVDPlayer: finished waiting
09:34:58 T:8080 NOTICE: CDVDPlayer::CloseFile()
09:34:58 T:8080 NOTICE: DVDPlayer: waiting for threads to exit
09:34:58 T:8080 NOTICE: DVDPlayer: finished waiting

Tried to use vlc to open this stream without problem.

Tried on different windows pc and amazon fire tv. Same issue.

[Ned Scott]

Can you post a full debug log (wiki)?

[wangsu]

The debug log in xbmclogs.com

15.0-RC2:
http://xbmclogs.com/pkatdv2ff

Release Kodi x32 build
http://xbmclogs.com/pwepiur4b

[Ned Scott]

Does it work if you use VLC or another UPnP player?

[wangsu]

Yes. VLC working well and Kodi 14.2 working well.

VLC debug log
http://xbmclogs.com/p44zwkf8w

Kodi 14.2 debug log which is working well

http://xbmclogs.com/pxfqfaqbc

Does it work if you use VLC or another UPnP player?

[sd-jafa]

Nick here from Silicondust.

HTTP 406 is "not acceptable".

Most likely Kodi is sending a :"RANGE 0-" request which a DLNA compliant media server (HDHomeRun) is explicitly required to reject with a 406 error if the content is a live not-seekable source (ie TV broadcast). This is braindead but required to pass certification.

DLNA also requires the client send a RANGE request on ALL requests for normal seekable content even if starting from the beginning. Also braindead but required to pass certification.

I will need to look up the exact way a client is meant to determine if a RANGE request is required or forbidden - will post back when I confirm.

BTW - as a quick test, if you remove the "dlna" parameter from the URL the HDHomeRun Kodi will ignore this silly DLNA rule and Kodi should work fine.

Nick

[Dhorlo]

All,

I can confirm the DLNA parameter seems to be the issue although Nick (sd-jafa) will probably have the definitive answer. I created a m3u playlist for a single channel, 9, for the IPTV Simple Client with the following (I'm using the transcode parameter because I have a HDHomerun Plus/Extend):

Playlist (in this box to maintain "9 With DLNA" URL w/o truncation. URL is auto-truncated in text blocks below)

Code:

#EXTM3U
#EXTINF:0 tvg-id="" tvg-logo="",9 No DLNA
http://192.168.1.145:5004/auto/v9.1?transcode=mobile
#EXTINF:0 tvg-id="" tvg-logo="",9 With DLNA
http://192.168.1.145:5004/auto/v9.1?dlna&transcode=mobile
#EXTINF:0 tvg-id="" tvg-logo="",9 UPnP
upnp://77C05281-B15A-3C64-ADE5-AB1DB36C07D4/Channels%2fv9.1/

I then tired each "channel", reviewed the log and found the following:

No DLNA
09:37:06 T:17520 DEBUG: PVRManager - PVR::CPVRManager::OpenLiveStream - opening live stream on channel '9 No DLNA'
09:37:06 T:17520 DEBUG: opening live stream on url 'http://192.168.1.145:5004/auto/v9.1?transcode=mobile'
09:37:06 T:17520 DEBUG: PVRFile - XFILE::CPVRFile::Open - playback has started on filename pvr://channels/tv/All channels/pvr.iptvsimple_1469962204.pvr
09:37:06 T:17520 INFO: XCURL:llLibCurlGlobal::easy_aquire - Created session to http://192.168.1.145
09:37:06 T:17520 DEBUG: CCurlFile::GetMimeType - http://192.168.1.145:5004/auto/v9.1?transcode=mobile -> video/mpeg
09:37:06 T:17520 DEBUG: CFileCache::Open - opening <auto/v9.1> using cache
09:37:06 T:17520 DEBUG: CurlFile::Open(0C73EFC8) http://192.168.1.145:5004/auto/v9.1?transcode=mobile
09:37:06 T:18308 DEBUG: Version Check: Version installed {u'major': 15, u'tag': u'stable', u'minor': 0, u'revision': u'20150721-2f34a0c'}
09:37:06 T:18308 INFO: CPythonInvoker(0, E:\Users\Louis\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\service.py): script successfully run
09:37:06 T:18308 INFO: Python script stopped
09:37:06 T:18308 DEBUG: Thread LanguageInvoker 18308 terminating
09:37:07 T:18056 DEBUG: ------ Window Init (DialogBusy.xml) ------
09:37:07 T:17520 DEBUG: CDVDInputStreamPVRManager::Open - stream opened: http://192.168.1.145:5004/auto/v9.1?transcode=mobile

With DLNA (URL is truncated, see above)
09:37:14 T:15768 DEBUG: PVRManager - PVR::CPVRManager::OpenLiveStream - opening live stream on channel '9 With DLNA'
09:37:14 T:15768 DEBUG: opening live stream on url 'http://192.168.1.145:5004/auto/v9.1?dlna&transcode=mobile'
09:37:14 T:15768 DEBUG: PVRFile - XFILE::CPVRFile::Open - playback has started on filename pvr://channels/tv/All channels/pvr.iptvsimple_806924480.pvr
09:37:14 T:15768 DEBUG: CCurlFile::GetMimeType - http://192.168.1.145:5004/auto/v9.1?dlna...ode=mobile -> video/mpeg
09:37:14 T:15768 DEBUG: CFileCache::Open - opening <auto/v9.1> using cache
09:37:14 T:15768 DEBUG: CurlFile::Open(0C6087C0) http://192.168.1.145:5004/auto/v9.1?dlna...ode=mobile
09:37:14 T:15768 ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 406
09:37:14 T:15768 ERROR: CCurlFile::Open failed with code 406 for http://192.168.1.145:5004/auto/v9.1?dlna...ode=mobile
09:37:14 T:15768 ERROR: XFILE::CFileCache::Open - failed to open source <http://192.168.1.145:5004/auto/v9.1?dlna&transcode=mobile>
09:37:14 T:15768 ERROR: CDVDInputStreamPVRManager::Open - error opening [http://192.168.1.145:5004/auto/v9.1?dlna...ode=mobile]
09:37:14 T:15768 ERROR: CDVDPlayer::OpenInputStream - error opening [pvr://channels/tv/All channels/pvr.iptvsimple_806924480.pvr]
09:37:14 T:15768 NOTICE: CDVDPlayer::OnExit()
09:37:14 T:15768 DEBUG: CDVDInputStreamPVRManager::Close - stream closed

UPnP (URL is truncated, see above)
09:37:20 T:17188 DEBUG: PVRManager - PVR::CPVRManager::OpenLiveStream - opening live stream on channel '9 UPnP'
09:37:20 T:17188 DEBUG: opening live stream on url 'upnp://77C05281-B15A-3C64-ADE5-AB1DB36C07D4/Channels%2fv9.1/'
09:37:20 T:17188 DEBUG: PVRFile - XFILE::CPVRFile::Open - playback has started on filename pvr://channels/tv/All channels/pvr.iptvsimple_733508962.pvr
09:37:20 T:17188 DEBUG: CUPnPDirectory::GetResource - resource protocol info 'http-get:*:video/mpegLNA.ORG_PN=AVC_TS_HP_SD_AC3_ISO;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=8D100000000000000000000000000000'
09:37:20 T:17188 DEBUG: File::Open - redirecting implementation for upnp://77C05281-B15A-3C64-ADE5-AB1DB36C07D4/Channels%2fv9.1/
09:37:20 T:17188 DEBUG: CurlFile::Open(0C6087C0) http://192.168.1.145:5004/auto/v9.1?dlna...ode=mobile
09:37:20 T:17188 ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 406
09:37:20 T:17188 ERROR: CCurlFile::Open failed with code 406 for http://192.168.1.145:5004/auto/v9.1?dlna...ode=mobile
09:37:20 T:17188 ERROR: CDVDInputStreamPVRManager::Open - error opening [upnp://77C05281-B15A-3C64-ADE5-AB1DB36C07D4/Channels%2fv9.1/]
09:37:20 T:17188 ERROR: CDVDPlayer::OpenInputStream - error opening [pvr://channels/tv/All channels/pvr.iptvsimple_733508962.pvr]
09:37:20 T:17188 NOTICE: CDVDPlayer::OnExit()
09:37:20 T:17188 DEBUG: CDVDInputStreamPVRManager::Close - stream closed

Don't know if this helps anyone, but I figured more info is better than less.
Full log if you are interested: http://xbmclogs.com/p1tnmp2p9

edit: spelling, grammar

[wsnipex]

please enable curl trace logging under the extra logging section

[wangsu]

Please view line 1325.

09:10:53 T:300 DEBUG: Curl:ebug - TEXT: The requested URL returned error: 406 Not Acceptable

curl log enabled:

http://xbmclogs.com/pyhh3vqjy

please enable curl trace logging under the extra logging section

[wsnipex]

thanks, this confirms sd-jafas suspicion:
09:10:53 T:300 DEBUG: CurlFile::Open(0947CD60) http://192.168.111.112:5004/auto/v502?dlna
09:10:53 T:300 DEBUG: Curl:ebug - TEXT: timeout on name lookup is not supported
09:10:53 T:300 DEBUG: Curl:ebug - TEXT: Trying 192.168.111.112...
09:10:53 T:300 DEBUG: Curl:ebug - TEXT: Connected to 192.168.111.112 (192.168.111.112) port 5004 (#0)
09:10:53 T:300 DEBUG: Curl:ebug - HEADER_OUT: GET /auto/v502?dlna HTTP/1.1
09:10:53 T:300 DEBUG: Curl:ebug - HEADER_OUT: Host: 192.168.111.112:5004
09:10:53 T:300 DEBUG: Curl:ebug - HEADER_OUT: Range: bytes=0-
09:10:53 T:300 DEBUG: Curl:ebug - HEADER_OUT: User-Agent: Kodi/15.0 (Windows NT 10.0; WOW64) App_Bitness/32 Version/15.0-Git:20150721-2f34a0c
09:10:53 T:300 DEBUG: Curl:ebug - HEADER_OUT: Accept: */*
09:10:53 T:300 DEBUG: Curl:ebug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
09:10:53 T:300 DEBUG: Curl:ebug - TEXT: The requested URL returned error: 406 Not Acceptable

now the question is, what is the correct behavior here, how do we detect if its a live stream?

[onizuka]

Just bumping this little fella........my UPNP is flat out not working with the latest beta 3 of OPENELEC. (i.e. When searching for UPNP devices, my HDHomerun is not found. Sometimes it will when turning off, then turning on UPNP. However, the links do not work after adding HDHR source.) Just wanted to confirm if this is the same for everyone, or is it isolated to OPENELEC.

Thanks!

[wangsu]

I can find it always. But cannot play in this version.

Just bumping this little fella........my UPNP is flat out not working with the latest beta 3 of OPENELEC. (i.e. When searching for UPNP devices, my HDHomerun is not found. Sometimes it will when turning off, then turning on UPNP. However, the links do not work after adding HDHR source.) Just wanted to confirm if this is the same for everyone, or is it isolated to OPENELEC.

Thanks!

[Tim2709]

A little more feedback on this topic. I can also confirm that using UPnP to stream from a Panasonic Diga HDD Recorder is broken in Isengard. It worked really well in Helix 14.2.

So the symptoms are from Video -> Files -> Channel List clicking on an entry shows the spinning icon for a few seconds and then nothing, try again and sometimes you get an error message and told to go look in the log file.

Strangely, whilst Kodi won't open a live TV channel from the tuner, it would play a recorded show from the HDD.

I can't see where to attach the logfile but here is an extract and I see ERROR 406 same as above posts in this thread:

11:30:30 T:900 NOTICE: DVDPlayer: Opening: http://192.168.0.13:60001/0004-233A-1048-10BF-801D_BDY
11:30:30 T:900 WARNING: CDVDMessageQueue(player):ut MSGQ_NOT_INITIALIZED
11:30:30 T:900 NOTICE: DXVA::CProcessorHD:reInit - The Direct3d device doesn't support DXVA-HD.
11:30:30 T:900 NOTICE: CWinRenderer:reinit - could not init DXVA-HD processor - skipping
11:30:30 T:8272 NOTICE: Thread DVDPlayer start, auto delete: false
11:30:30 T:8272 NOTICE: Creating InputStream
11:30:40 T:8272 ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 406
11:30:40 T:8272 ERROR: CCurlFile::Open failed with code 406 for http://192.168.0.13:60001/0004-233A-1048-10BF-801D_BDY
11:30:40 T:8272 ERROR: XFILE::CFileCache::Open - failed to open source <http://192.168.0.13:60001/0004-233A-1048-10BF-801D_BDY>
11:30:40 T:8272 ERROR: CDVDPlayer::OpenInputStream - error opening [http://192.168.0.13:60001/0004-233A-1048-10BF-801D_BDY]
11:30:40 T:8272 NOTICE: CDVDPlayer::OnExit()
11:30:40 T:900 ERROR: Playlist Player: skipping unplayable item: 0, path [upnp://4D454930-0100-1000-8000-20C6EB7175E5/0004-233A-1048-10BF-801D/]
11:30:40 T:5000 NOTICE: Thread BackgroundLoader start, auto delete: false
11:30:40 T:900 NOTICE: CDVDPlayer::CloseFile()
11:30:40 T:900 NOTICE: DVDPlayer: waiting for threads to exit
11:30:40 T:900 NOTICE: DVDPlayer: finished waiting
11:30:40 T:900 NOTICE: CDVDPlayer::CloseFile()
11:30:40 T:900 NOTICE: DVDPlayer: waiting for threads to exit
11:30:40 T:900 NOTICE: DVDPlayer: finished waiting

[Ned Scott]

http://trac.kodi.tv/ticket/14204

[Ned Scott]

Maybe some hints here from the v12 fix discussion: https://github.com/xbmc/xbmc/pull/2675