Threaded Mode | Linear Mode

Mark · 04-12-2015, 19:06

Hallo Mark,

the Denon Heos recieved a firmewareupdate but the situation is the same as before. When I play a normal flac file within the Heos App all is well. The Log shows then the following:

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~Start

18:15:44.407 Thread-4844: HTTPService: accepted incoming connection
18:15:44.407 Thread-4844: HTTPService: waiting for incoming connection
18:15:44.407 Thread-4843: HTTPService: received request, Socket[addr=/192.168.77.42,port=33256,localport=9790]
18:15:44.408 Thread-4843: HTTPService: adding connection org.jminim.lib.HTTPConnection@246293
18:15:44.408 Thread-5128: HTTPConnection: reading HTTP request
18:15:44.408 Thread-5127: HTTPConnection: writer thread waiting for request
18:15:44.434 Thread-5128: GET /minimserver/*/music/Flac/Alben/3*20Doors*20Down/3*20Doors*20Down/3*20Doors*20Down*20-*2002*20-*20Citizen*20Soldier.flac HTTP/1.1
18:15:44.434 Thread-5128: Range: bytes=6414200-, User-Agent: AvegaMediaServer/2.0 Linux/2.6, Host: 192.168.77.16:9790, Accept: */*, transferMode.dlna.org: Streaming
18:15:44.434 Thread-5128: HTTPConnection: reading HTTP request
18:15:44.434 Thread-5127: HTTPConnection: writer thread processing request
18:15:44.436 Thread-5127: HTTP/1.1 206 Partial Content, Content-Range: bytes 6414200-29598794/29598795, Accept-Ranges: bytes, Date: Fri, 04 Dec 2015 17:15:44 GMT, Content-Length: 23184595, Content-Type: audio/x-flac, Connection: keep-alive, Last-Modified: Sat, 17 Oct 2009 17:26:57 GMT, transferMode.dlna.org: Streaming
18:15:44.437 Thread-5127: writing data: total=23184595 from file Flac/Alben/3 Doors Down/3 Doors Down/3 Doors Down - 02 - Citizen Soldier.flac
18:15:44.437 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.437 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.442 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.448 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.457 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.497 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.537 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.579 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.579 Thread-5127: writing 16384 bytes: total=23184595
18:15:44.579 Thread-5127: writing 16384 bytes: total=23184595
18:15:48.138 Thread-5127: writing: maxRead=0, minRead=0, maxWrite=3559, minWrite=0, numReads=10, numWrites=10, avgWrite=370
18:15:48.139 Thread-5127: writing 16384 bytes: total=23184595
18:15:48.337 Thread-5127: writing 16384 bytes: total=23184595
18:15:48.338 Thread-5127: writing 16384 bytes: total=23184595
18:15:48.457 Thread-5127: writing 16384 bytes: total=23184595
18:15:48.458 Thread-5127: writing 16384 bytes: total=23184595
18:15:48.697 Thread-5127: writing 16384 bytes: total=23184595
18:15:48.698 Thread-5127: writing 16384 bytes: total=23184595
18:15:48.937 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.117 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.118 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.357 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.357 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.477 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.478 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.742 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.937 Thread-5127: writing 16384 bytes: total=23184595
18:15:49.937 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.138 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.138 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.337 Thread-5127: writing: maxRead=0, minRead=0, maxWrite=265, minWrite=0, numReads=19, numWrites=19, avgWrite=115
18:15:50.337 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.337 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.577 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.817 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.817 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.978 Thread-5127: writing 16384 bytes: total=23184595
18:15:50.978 Thread-5127: writing 16384 bytes: total=23184595
18:15:51.177 Thread-5127: writing 16384 bytes: total=23184595
18:15:51.177 Thread-5127: writing 16384 bytes: total=23184595
18:15:51.417 Thread-5127: writing 16384 bytes: total=23184595
18:15:51.417 Thread-5127: writing 16384 bytes: total=23184595
18:15:51.717 Thread-5127: writing 16384 bytes: total=23184595
18:15:51.878 Thread-5127: writing 16384 bytes: total=23184595
18:15:51.878 Thread-5127: writing 16384 bytes: total=23184595
18:15:52.037 Thread-5127: writing 16384 bytes: total=23184595
18:15:52.037 Thread-5127: writing 16384 bytes: total=23184595
18:15:52.277 Thread-5127: writing 16384 bytes: total=23184595
18:15:52.277 Thread-5127: writing 16384 bytes: total=23184595
18:15:52.558 Thread-5127: writing: maxRead=0, minRead=0, maxWrite=300, minWrite=0, numReads=18, numWrites=18, avgWrite=123
18:15:52.558 Thread-5127: writing 16384 bytes: total=23184595
18:15:52.720 Thread-5128: HTTPConnection: connection closed by client
18:15:52.721 Thread-5127: FileResource: close
18:15:52.722 Thread-5127: ServerResourceBase: close
18:15:52.722 Thread-5128: ServerResourceBase: forced socket close: Socket[addr=/192.168.77.42,port=33256,localport=9790]
18:15:52.722 Thread-5127: HTTPConnection: SocketException writing HTTP response to Socket[addr=/192.168.77.42,port=33256,localport=9790]
18:15:52.723 Thread-5128: HTTPService: removing connection org.jminim.lib.HTTPConnection@246293.

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~Stop

Now I stop the flac file playing and change to my radio.m3u file and try to start a radio stream. Before I get the timeout a got:

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~Start

18:18:29.777 Thread-4847: ContentDirectory action: Browse 0$folders$f217
18:18:29.777 Thread-4847: version=1, adapter=192.168.77.16, uriPrefix=http://192.168.77.16:9791/cd1079f0-5429-4065-906a-99184442f145/Upnp/resource/, clientEndpoint=192.168.77.49:51437
18:18:29.777 Thread-4847: BrowseFlag=BrowseDirectChildren StartingIndex=0 RequestedCount=100 Filter=dc:title,dc:date,upnp:artist,dc:creator,upnp:class,upnp:album,upnp:genre,upnp:albumArtURI,res,res@duration,res@reliability,upnp:originalTrackNumber,avega_media_server:media_library_database,container@searchable SortCriteria=
18:18:29.777 Thread-4847: using resource URI prefix http://192.168.77.16:9790/
18:18:29.778 Thread-4847: removing container from cache: 0$folders$f218$*p1
18:18:29.778 Thread-4847: removing container from cache: 0$folders$f218
18:18:29.779 Thread-4847: adding container to cache: 0$folders$f217
18:18:29.779 Thread-4847: browsing children of container com.minimserver.Server$FolderContainer@963a4f
18:18:29.779 Thread-4847: ContentDirectory response: Browse 0$folders$f217
18:18:29.779 Thread-4847: NumberReturned=2 TotalMatches=2 UpdateID=37
18:18:29.779 Thread-4847: Result=<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns Big Grin

c="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/"><container id="0$folders$f217$tagview" parentID="0$folders$f217" restricted="1" searchable="1"><dc:title>>> Tag View</dc:title><upnp:class>object.container</upnp:class></container><container id="0$folders$f217$*p0" parentID="0$folders$f217" childCount="10" restricted="1" searchable="1"><dc:title>00_radio</dc:title><upnp:class>object.container.playlistContainer</upnp:class></container></DIDL-Lite>
18:18:30.890 Thread-4850: ContentDirectory action: Browse 0$folders$f217$*p0
18:18:30.890 Thread-4850: version=1, adapter=192.168.77.16, uriPrefix=http://192.168.77.16:9791/cd1079f0-5429-4065-906a-99184442f145/Upnp/resource/, clientEndpoint=192.168.77.49:60395
18:18:30.890 Thread-4850: BrowseFlag=BrowseDirectChildren StartingIndex=0 RequestedCount=100 Filter=dc:title,dc:date,upnp:artist,dc:creator,upnp:class,upnp:album,upnp:genre,upnp:albumArtURI,res,res@duration,res@reliability,upnp:originalTrackNumber,avega_media_server:media_library_database,container@searchable SortCriteria=
18:18:30.891 Thread-4850: using resource URI prefix http://192.168.77.16:9790/
18:18:30.891 Thread-4850: adding container to cache: 0$folders$f217$*p0
18:18:30.891 Thread-4850: browsing children of container com.minimserver.Server$PlaylistContainer@b2ecf5
18:18:30.892 Thread-4850: ContentDirectory response: Browse 0$folders$f217$*p0
18:18:30.892 Thread-4850: NumberReturned=10 TotalMatches=10 UpdateID=37
18:18:30.892 Thread-4850: Result=<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns Big Grin

c="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/"><item id="0$folders$f217$*p0$*c0" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[AntMV] Antenne MV</dc:title><res protocolInfo="http-get:*:audio/aac:*">http://192.168.77.16:9790/minimstreamer/*/AntMV/$!stream.aac</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c1" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[RHH] Radio Hamburg</dc:title><res protocolInfo="http-get:*:audio/mpeg:*">http://192.168.77.16:9790/minimstreamer/*/RHH/$!stream.mp3</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c2" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[NDR2] NDR2 - Region Meckelenburg-Vorpommern</dc:title><res protocolInfo="http-get:*:audio/mpeg:*">http://192.168.77.16:9790/minimstreamer/*/NDR2/$!stream.mp3</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c3" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[AB1] Antenne Bayern</dc:title><res protocolInfo="http-get:*:audio/mpeg:*">http://192.168.77.16:9790/minimstreamer/*/AB1/$!stream.mp3</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c4" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[RAHM] ROCK ANTENNE Heavy Metal</dc:title><res protocolInfo="http-get:*:audio/aac:*">http://192.168.77.16:9790/minimstreamer/*/RAHM/$!stream.aac</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c5" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[OW] Ostseewelle Hit Radio</dc:title><res protocolInfo="http-get:*:audio/mpeg:*">http://192.168.77.16:9790/minimstreamer/*/OW/$!stream.mp3</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c6" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[RBro] Radio Brocken</dc:title><res protocolInfo="http-get:*:audio/mpeg:*">http://192.168.77.16:9790/minimstreamer/*/RBro/$!stream.mp3</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c7" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[WDR2] WDR2</dc:title><res protocolInfo="http-get:*:audio/mpeg:*">http://192.168.77.16:9790/minimstreamer/*/WDR2/$!stream.mp3</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c8" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[NDR2 Rock] NDR2 Rock</dc:title><res protocolInfo="http-get:*:audio/mpeg:*">http://192.168.77.16:9790/minimstreamer/*/NDR2*20Rock/$!stream.mp3</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item><item id="0$folders$f217$*p0$*c9" parentID="0$folders$f217$*p0" restricted="1"><dc:title>[RAUTEC hristmas] RAUTE MUSIK Christmas Channel</dc:title><res protocolInfo="http-get:*:audio/mpeg:*">http://192.168.77.16:9790/minimstreamer/*/RAUTEC*20hristmas/$!stream.mp3</res><upnp:class>object.item.audioItem.audioBroadcast</upnp:class></item></DIDL-Lite>

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~Stop

and then the log shows nothing and the Heos App timeout error.
In BubbleUpnP it looks like:

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~Start

18:37:27.154 Thread-4844: HTTPService: accepted incoming connection
18:37:27.154 Thread-4844: HTTPService: waiting for incoming connection
18:37:27.154 Thread-4843: HTTPService: received request, Socket[addr=/192.168.77.42,port=57164,localport=9790]
18:37:27.154 Thread-4843: HTTPService: adding connection org.jminim.lib.HTTPConnection@1870bb
18:37:27.155 Thread-5177: HTTPConnection: writer thread waiting for request
18:37:27.155 Thread-5178: HTTPConnection: reading HTTP request
18:37:27.155 Thread-5178: GET /minimstreamer/*/RAUTEC*20hristmas/$!stream.mp3 HTTP/1.1
18:37:27.155 Thread-5178: User-Agent: AvegaMediaServer/2.0 Linux/2.6, Host: 192.168.77.16:9790, Accept: */*, transferMode.dlna.org: Streaming
18:37:27.156 Thread-5178: HTTPConnection: reading HTTP request
18:37:27.156 Thread-5177: HTTPConnection: writer thread processing request
18:37:27.156 Thread-5177: Streamer: processing HTTP request
18:37:27.156 Thread-5177: Streamer: releaseChains
18:37:27.156 Thread-5177: Streamer: releasing chain: RAUTEC HRISTMAS
18:37:27.156 Thread-5177: Streamer: streamID is RAUTEC hristmas
18:37:27.156 Thread-5177: Streamer: URL scheme is http
18:37:27.156 Thread-5177: Streamer: request path is christmas-high.rautemusik.fm/listen.pls/$!stream.mp3
18:37:27.156 Thread-5177: Streamer: transcodeType is
18:37:27.157 Thread-5177: ChainImpl: addSource org.jstreamer.core.ChainSource@1a274c4
18:37:27.157 Thread-5177: ChainSource: init
18:37:27.157 Thread-5177: ChainImpl: getting property .inputBufferSize
18:37:27.157 Thread-5177: ChainImpl: property .inputBufferSize has null value
18:37:27.157 Thread-5177: ChainImpl: getting property .inputBuffers
18:37:27.157 Thread-5177: ChainImpl: property .inputBuffers has null value
18:37:27.157 Thread-5177: ChainSource: inputBufferSize=5000 inputBuffers=2
18:37:27.157 Thread-5177: BufferPool: init
18:37:27.157 Thread-5177: ChainSource: source created
18:37:27.158 Thread-5177: ChainImpl: getting property scfilter.link
18:37:27.158 Thread-5177: ChainImpl: property scfilter.link has literal value 'source'
18:37:27.158 Thread-5177: ChainImpl: getting property scfilter.mode
18:37:27.158 Thread-5177: ChainImpl: property scfilter.mode has literal value 'out'
18:37:27.158 Thread-5177: ChainImpl: addFilter org.jstreamer.core.ChainSource@1a274c4
18:37:27.158 Thread-5177: LinkBase: adding output filter org.jstreamer.lib.ShoutcastFilter@19df228
18:37:27.158 Thread-5177: created new root chain org.jstreamer.core.ChainImpl@9f7eff
18:37:27.158 Thread-5177: ChainImpl: setting property .requestPath to 'christmas-high.rautemusik.fm/listen.pls'
18:37:27.158 Thread-5177: ChainImpl: setting property .requestMethod to 'GET'
18:37:27.159 Thread-5177: ChainImpl: setting property .requestHeaders to 'User-Agent: AvegaMediaServer/2.0 Linux/2.6
Accept: */*
transferMode.dlna.org: Streaming

'
18:37:27.159 Thread-5177: ChainImpl: addTarget org.jstreamer.core.ChainTarget@185ce0d
18:37:27.159 Thread-5177: ChainTarget: init
18:37:27.159 Thread-5177: LinkBase: adding input filter com.minimstreamer.HTTPRequestTarget$TargetFilter@1736ca7 isLast=true
18:37:27.159 Thread-5177: ChainTarget: target created
18:37:27.159 Thread-5177: ChainImpl: getting property adfilter.link
18:37:27.159 Thread-5177: ChainImpl: property adfilter.link has literal value 'request'
18:37:27.160 Thread-5177: ChainImpl: getting property adfilter.mode
18:37:27.160 Thread-5177: ChainImpl: property adfilter.mode has null value
18:37:27.160 Thread-5177: ChainImpl: addFilter org.jstreamer.core.ChainTarget@185ce0d
18:37:27.160 Thread-5177: LinkBase: adding input filter org.jstreamer.lib.FrameFilter@b081b0 isLast=false
18:37:27.160 Thread-5177: ChainImpl: setting property .timeout to '2000'
18:37:27.160 Thread-5177: ChainImpl: setting property object .@timeoutAction to com.minimstreamer.Streamer$TimeoutAction@e1b3f1
18:37:27.160 Thread-5177: ChainImpl: setting property object .@notifyStopped to com.minimstreamer.Streamer$NotifyStopped@77e11e
18:37:27.160 Thread-5177: ChainImpl: setting property object .@httpRequest to org.jminim.lib.HTTPConnection$Request@fe5da1
18:37:27.161 Thread-5177: ChainImpl: setting property object .@http to org.jminim.lib.HTTPContext@1c34b8c
18:37:27.161 Thread-5177: ChainImpl: setting property .transcodeType to ''
18:37:27.161 Thread-5177: ChainImpl: setting property object .@streamer to com.minimstreamer.Streamer@33da7a
18:37:27.161 Thread-5177: ChainImpl: setting property object .@parentChain to org.jstreamer.core.ChainImpl@9f7eff
18:37:27.161 Thread-5177: Streamer: adding request chain for org.jminim.lib.HTTPConnection$Request@fe5da1: org.jstreamer.core.ChainImpl@1dbe9a1
18:37:27.161 Thread-5177: ChainImpl: addTargetChain org.jstreamer.core.TargetChain@f82c7e
18:37:27.161 Thread-5177: TargetChain: init
18:37:27.161 Thread-5177: BufferPoolReader: new
18:37:27.162 Thread-5177: Buffer: createReader: org.jstreamer.core.Buffer@10b7d2b
18:37:27.162 Thread-5177: BufferReader: initial readSequence=1
18:37:27.162 Thread-5177: Buffer: createReader: org.jstreamer.core.Buffer@cb1605
18:37:27.162 Thread-5177: BufferReader: initial readSequence=1
18:37:27.162 Thread-5177: ChainImpl: start org.jstreamer.core.ChainImpl@9f7eff
18:37:27.162 Thread-5177: ChainImpl: startLinks org.jstreamer.core.ChainImpl@9f7eff
18:37:27.162 Thread-5177: ChainImpl: starting chain org.jstreamer.core.ChainImpl@9f7eff
18:37:27.162 Thread-5177: TargetChain: start
18:37:27.162 Thread-5177: TargetChain: starting subchain links
18:37:27.162 Thread-5177: ChainImpl: startLinks org.jstreamer.core.ChainImpl@1dbe9a1
18:37:27.162 Thread-5177: ChainImpl: starting chain org.jstreamer.core.ChainImpl@1dbe9a1
18:37:27.163 Thread-5177: ChainTarget: start
18:37:27.163 Thread-5177: ChainImpl: property obj request.@notifyStopped is reference to .@notifyStopped
18:37:27.163 Thread-5177: ChainImpl: property object request.@notifyStopped has value com.minimstreamer.Streamer$NotifyStopped@77e11e
18:37:27.163 Thread-5177: ChainImpl: property obj request.@httpRequest is reference to .@httpRequest
18:37:27.163 Thread-5177: ChainImpl: property object request.@httpRequest has value org.jminim.lib.HTTPConnection$Request@fe5da1
18:37:27.163 Thread-5177: ChainImpl: property obj request.@http is reference to .@http
18:37:27.163 Thread-5177: ChainImpl: property object request.@http has value org.jminim.lib.HTTPContext@1c34b8c
18:37:27.163 Thread-5177: ChainImpl: property obj request.@timeoutAction is reference to .@timeoutAction
18:37:27.163 Thread-5177: ChainImpl: property object request.@timeoutAction has value com.minimstreamer.Streamer$TimeoutAction@e1b3f1
18:37:27.163 Thread-5177: ChainImpl: getting property request.timeout
18:37:27.163 Thread-5177: ChainImpl: property request.timeout has initial value '$.timeout'
18:37:27.164 Thread-5177: ChainImpl: property .timeout has literal value '2000'
18:37:27.164 Thread-5177: ChainImpl: substitution property .timeout has value '2000'
18:37:27.164 Thread-5177: ChainImpl: property request.timeout has final value '2000'
18:37:27.164 Thread-5177: HTTPRequestTarget: timeout is 2000
18:37:27.164 Thread-5177: HTTPRequestTarget: initRequest socket=Socket[addr=/192.168.77.42,port=57164,localport=9790] this=com.minimstreamer.HTTPRequestTarget@18382ed outStr=com.minimstreamer.HTTPRequestTarget$ProxyOutputStream@1e00f89
18:37:27.164 Thread-5177: created timer java.util.Timer@170ce3b
18:37:27.164 Thread-5177: ChainTarget: target started
18:37:27.164 Thread-5177: ChainImpl: started chain org.jstreamer.core.ChainImpl@1dbe9a1
18:37:27.165 Thread-5177: TargetChain: subchain links started
18:37:27.165 Thread-5177: ChainSource: start
18:37:27.165 Thread-5177: ChainImpl: getting property source.resource
18:37:27.165 Thread-5177: ChainImpl: property source.resource has initial value '$.requestPath'
18:37:27.165 Thread-5177: ChainImpl: property .requestPath has literal value 'christmas-high.rautemusik.fm/listen.pls'
18:37:27.165 Thread-5177: ChainImpl: substitution property .requestPath has value 'christmas-high.rautemusik.fm/listen.pls'
18:37:27.165 Thread-5177: ChainImpl: property source.resource has final value 'christmas-high.rautemusik.fm/listen.pls'
18:37:27.165 Thread-5177: ChainImpl: getting property source.requestHeaders
18:37:27.165 Thread-5177: ChainImpl: property source.requestHeaders has initial value '$.requestHeaders'
18:37:27.165 Thread-5177: ChainImpl: property .requestHeaders has literal value 'User-Agent: AvegaMediaServer/2.0 Linux/2.6
Accept: */*
transferMode.dlna.org: Streaming

'
18:37:27.165 Thread-5177: ChainImpl: substitution property .requestHeaders has value 'User-Agent: AvegaMediaServer/2.0 Linux/2.6
Accept: */*
transferMode.dlna.org: Streaming

'
18:37:27.166 Thread-5177: ChainImpl: property source.requestHeaders has final value 'User-Agent: AvegaMediaServer/2.0 Linux/2.6
Accept: */*
transferMode.dlna.org: Streaming

'
18:37:27.166 Thread-5177: ChainImpl: getting property source.delayHLS
18:37:27.166 Thread-5177: ChainImpl: property source.delayHLS has initial value '$.delayHLS'
18:37:27.166 Thread-5177: ChainImpl: property .delayHLS has null value
18:37:27.166 Thread-5177: ChainImpl: substitution property .delayHLS has value ''
18:37:27.166 Thread-5177: ChainImpl: property source.delayHLS has final value ''
18:37:27.166 Thread-5177: HTTPSource: range value is 'null'
18:37:27.197 Thread-5177: HTTPSource: created outgoing socket Socket[addr=christmas-high.rautemusik.fm/87.230.101.9,port=80,localport=54517]
18:37:27.197 Thread-5177: HTTPSource: sending HTTP request:
GET /listen.pls HTTP/1.1
Host: christmas-high.rautemusik.fm
Icy-MetaData: 1
Connection: close
User-Agent: AvegaMediaServer/2.0 Linux/2.6
Accept: */*
transferMode.dlna.org: Streaming

18:37:27.226 Thread-5177: HTTPSource: stream response status HTTP/1.1 200 OK
18:37:27.226 Thread-5177: ChainImpl: setting property source.status to 'HTTP/1.1 200 OK
'
18:37:27.227 Thread-5177: Connection: Keep-Alive
Content-Disposition: attachment; filename=listen.pls
Content-Type: audio/x-scpls
Content-Length: 121

18:37:27.227 Thread-5177: ChainImpl: setting property source.responseHeaders to 'Connection: Keep-Alive
Content-Disposition: attachment; filename=listen.pls
Content-Type: audio/x-scpls
Content-Length: 121
'
18:37:27.227 Thread-5177: HTTPSource: content type is 'audio/x-scpls'
18:37:27.227 Thread-5177: HTTPSource: reading playlist from URL http://christmas-high.rautemusik.fm/listen.pls
18:37:27.227 Thread-5177: HTTPSource: found valid stream URL http://christmas-high.rautemusik.fm
18:37:27.256 Thread-5177: HTTPSource: created outgoing socket Socket[addr=christmas-high.rautemusik.fm/87.230.101.9,port=80,localport=54519]
18:37:27.256 Thread-5177: HTTPSource: sending HTTP request:
GET / HTTP/1.1
Host: christmas-high.rautemusik.fm
Icy-MetaData: 1
Connection: close
User-Agent: AvegaMediaServer/2.0 Linux/2.6
Accept: */*
transferMode.dlna.org: Streaming

18:37:27.285 Thread-5177: HTTPSource: stream response status HTTP/1.1 302 Found
18:37:27.285 Thread-5177: ChainImpl: setting property source.status to 'HTTP/1.1 302 Found
'
18:37:27.285 Thread-5177: Location: http://95.141.24.115:80/
Content-Length: 0
Connection: close
Content-Type: text/html

18:37:27.286 Thread-5177: HTTPSource: redirect location is 'http://95.141.24.115:80/'
18:37:27.286 Thread-5177: HTTPSource: using redirect stream URL http://95.141.24.115:80/
18:37:27.314 Thread-5177: HTTPSource: created outgoing socket Socket[addr=/95.141.24.115,port=80,localport=54164]
18:37:27.314 Thread-5177: HTTPSource: sending HTTP request:
GET / HTTP/1.1
Host: 95.141.24.115
Icy-MetaData: 1
Connection: close
User-Agent: AvegaMediaServer/2.0 Linux/2.6
Accept: */*
transferMode.dlna.org: Streaming

18:37:27.349 Thread-5177: HTTPSource: stream response status ICY 200 OK
18:37:27.349 Thread-5177: ChainImpl: setting property source.status to 'ICY 200 OK
'
18:37:27.367 Thread-5177: icy-notice1: This stream requires <a href="http://www.winamp.com/">Winamp</a> 
icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.8 
icy-name:#MUSIK.CHRISTMAS - WWW://CHRISTMAS-CHANNEL.COM - 24H CHRISTMAS AND HOLIDAY SONGS - BY RAUTEMUSIK.FM
icy-genre:Christmas Holiday Xmas
icy-url:http://www.christmas-channel.com
content-type:audio/mpeg
icy-pub:1
icy-metaint:32768
icy-br:192

18:37:27.367 Thread-5177: ChainImpl: setting property source.responseHeaders to 'icy-notice1: This stream requires <a href="http://www.winamp.com/">Winamp</a> 
icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.8 
icy-name:#MUSIK.CHRISTMAS - WWW://CHRISTMAS-CHANNEL.COM - 24H CHRISTMAS AND HOLIDAY SONGS - BY RAUTEMUSIK.FM
icy-genre:Christmas Holiday Xmas
icy-url:http://www.christmas-channel.com
content-type:audio/mpeg
icy-pub:1
icy-metaint:32768
icy-br:192
'
18:37:27.367 Thread-5177: HTTPSource: content type is 'audio/mpeg'
18:37:27.367 Thread-5177: ChainImpl: setting property source.contentType to 'audio/mpeg'
18:37:27.367 Thread-5177: ChainImpl: setting property source.contentFormat to 'mp3'
18:37:27.367 Thread-5177: ChainImpl: setting property source.responseMessage to ''
18:37:27.367 Thread-5177: ChainImpl: setting property source.sourcePath to '/'
18:37:27.368 Thread-5177: ChainSource: source started
18:37:27.368 Thread-5177: ChainImpl: started chain org.jstreamer.core.ChainImpl@9f7eff
18:37:27.368 Thread-5177: ChainImpl: runLinks org.jstreamer.core.ChainImpl@9f7eff
18:37:27.368 Thread-5177: ChainImpl: running links for chain org.jstreamer.core.ChainImpl@9f7eff
18:37:27.368 Thread-5177: TargetChain: run
18:37:27.368 Thread-5177: TargetChain: running subchain links
18:37:27.368 Thread-5177: ChainImpl: runLinks org.jstreamer.core.ChainImpl@1dbe9a1
18:37:27.368 Thread-5177: ChainImpl: running links for chain org.jstreamer.core.ChainImpl@1dbe9a1
18:37:27.368 Thread-5177: ChainTarget: run
18:37:27.368 Thread-5177: ChainImpl: getting property request.transcodeType
18:37:27.369 Thread-5177: ChainImpl: property request.transcodeType has initial value '$.transcodeType'
18:37:27.369 Thread-5177: ChainImpl: property .transcodeType has literal value ''
18:37:27.369 Thread-5177: ChainImpl: substitution property .transcodeType has value ''
18:37:27.369 Thread-5177: ChainImpl: property request.transcodeType has final value ''
18:37:27.369 Thread-5177: ChainImpl: getting property request.transcodeLimit
18:37:27.369 Thread-5177: ChainImpl: property request.transcodeLimit has null value
18:37:27.369 Thread-5177: ChainImpl: getting property request.contentType
18:37:27.369 Thread-5177: ChainImpl: property request.contentType has initial value '$/source.contentType'
18:37:27.369 Thread-5177: ChainImpl: property source.contentType has literal value 'audio/mpeg'
18:37:27.369 Thread-5177: ChainImpl: substitution property /source.contentType has value 'audio/mpeg'
18:37:27.370 Thread-5177: ChainImpl: property request.contentType has final value 'audio/mpeg'
18:37:27.370 Thread-5177: ChainImpl: property obj request.@streamer is reference to .@streamer
18:37:27.370 Thread-5177: ChainImpl: property object request.@streamer has value com.minimstreamer.Streamer@33da7a
18:37:27.370 Thread-5177: ChainTarget: target running
18:37:27.370 Thread-5177: ChainTarget: starting writer thread
18:37:27.370 Thread-5177: ChainImpl: links running for chain org.jstreamer.core.ChainImpl@1dbe9a1
18:37:27.370 Thread-5180: ChainTarget: calling writeStream target=com.minimstreamer.HTTPRequestTarget@18382ed
18:37:27.370 Thread-5177: TargetChain: subchain links running
18:37:27.371 Thread-5180: BufferReader: writeStream: buffer=org.jstreamer.core.Buffer@10b7d2b
18:37:27.371 Thread-5177: ChainSource: run
18:37:27.371 Thread-5180: Buffer: fillBuffer writeSequence=0 readSequence=1: org.jstreamer.core.Buffer@10b7d2b
18:37:27.371 Thread-5177: ChainSource: source running
18:37:27.371 Thread-5180: Buffer: read operation waiting for buffer data: org.jstreamer.core.Buffer@10b7d2b
18:37:27.371 Thread-5177: ChainSource: starting reader thread
18:37:27.371 Thread-5177: ChainImpl: links running for chain org.jstreamer.core.ChainImpl@9f7eff
18:37:27.371 Thread-5181: Buffer: input stream returned 5000 bytes: org.jstreamer.core.Buffer@10b7d2b
18:37:27.371 Thread-5177: Streamer: waiting for completion of network request
18:37:27.372 Thread-5181: Buffer: start of data 0xfffbb264
18:37:27.372 Thread-5177: Streamer.NotifyStopped: waitFor
18:37:27.372 Thread-5177: Streamer.NotifyStopped: waiting
18:37:27.372 Thread-5181: Buffer: calling output filter 0 for 5000 bytes: org.jstreamer.core.Buffer@10b7d2b
18:37:27.372 Thread-5181: Buffer: start of data 0xfffbb264
18:37:27.372 Thread-5181: ChainImpl: getting property scfilter.responseHeaders
18:37:27.373 Thread-5181: ChainImpl: property scfilter.responseHeaders has initial value '$source.responseHeaders'
18:37:27.373 Thread-5181: ChainImpl: property source.responseHeaders has literal value 'icy-notice1: This stream requires <a href="http://www.winamp.com/">Winamp</a> 
icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.8 
icy-name:#MUSIK.CHRISTMAS - WWW://CHRISTMAS-CHANNEL.COM - 24H CHRISTMAS AND HOLIDAY SONGS - BY RAUTEMUSIK.FM
icy-genre:Christmas Holiday Xmas
icy-url:http://www.christmas-channel.com
content-type:audio/mpeg
icy-pub:1
icy-metaint:32768
icy-br:192
'
18:37:27.373 Thread-5181: ChainImpl: substitution property source.responseHeaders has value 'icy-notice1: This stream requires <a href="http://www.winamp.com/">Winamp</a> 
icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.8 
icy-name:#MUSIK.CHRISTMAS - WWW://CHRISTMAS-CHANNEL.COM - 24H CHRISTMAS AND HOLIDAY SONGS - BY RAUTEMUSIK.FM
icy-genre:Christmas Holiday Xmas
icy-url:http://www.christmas-channel.com
content-type:audio/mpeg
icy-pub:1
icy-metaint:32768
icy-br:192
'
18:37:27.373 Thread-5181: ChainImpl: property scfilter.responseHeaders has final value 'icy-notice1: This stream requires <a href="http://www.winamp.com/">Winamp</a> 
icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.8 
icy-name:#MUSIK.CHRISTMAS - WWW://CHRISTMAS-CHANNEL.COM - 24H CHRISTMAS AND HOLIDAY SONGS - BY RAUTEMUSIK.FM
icy-genre:Christmas Holiday Xmas
icy-url:http://www.christmas-channel.com
content-type:audio/mpeg
icy-pub:1
icy-metaint:32768
icy-br:192
'
18:37:27.373 Thread-5181: ShoutcastFilter: response headers=icy-notice1: This stream requires <a href="http://www.winamp.com/">Winamp</a> 
icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.8 
icy-name:#MUSIK.CHRISTMAS - WWW://CHRISTMAS-CHANNEL.COM - 24H CHRISTMAS AND HOLIDAY SONGS - BY RAUTEMUSIK.FM
icy-genre:Christmas Holiday Xmas
icy-url:http://www.christmas-channel.com
content-type:audio/mpeg
icy-pub:1
icy-metaint:32768
icy-br:192

18:37:27.373 Thread-5181: ShoutcastFilter: icy=379, endIcy=396
18:37:27.373 Thread-5181: ShoutcastFilter: icyMetaint=32768
18:37:27.374 Thread-5181: ChainImpl: setting property scfilter.isIcy to 'true'
18:37:27.374 Thread-5181: ShoutcastFilter: chunk start=0, length=5000
18:37:27.374 Thread-5181: Buffer: releaseFull: count=1, writeSequence=1: org.jstreamer.core.Buffer@10b7d2b
18:37:27.374 Thread-5180: BufferReader: calling filter 0 for 5000 bytes: buffer=org.jstreamer.core.Buffer@10b7d2b
18:37:27.374 Thread-5181: Buffer: input stream returned 5000 bytes: org.jstreamer.core.Buffer@cb1605
18:37:27.374 Thread-5180: BufferReader: start of data 0xfffbb264
18:37:27.374 Thread-5181: Buffer: start of data 0x86206b2e
18:37:27.374 Thread-5180: ChainImpl: getting property adfilter.sourceResponseHeaders
18:37:27.374 Thread-5181: Buffer: calling output filter 0 for 5000 bytes: org.jstreamer.core.Buffer@cb1605
18:37:27.375 Thread-5180: ChainImpl: property adfilter.sourceResponseHeaders has initial value '$/source.responseHeaders'
18:37:27.375 Thread-5181: Buffer: start of data 0x86206b2e
18:37:27.375 Thread-5180: ChainImpl: property source.responseHeaders has literal value 'icy-notice1: This stream requires <a href="http://www.winamp.com/">Winamp</a> 
icy-notice2:SHOUTcast Distributed Network Audio Server/Linux v1.9.8 
icy-name:#MUSIK.CHRISTMAS - WWW://CHRISTMAS-CHANNEL.COM - 24H CHRISTMAS AND HOLIDAY SONGS - BY RAUTEMUSIK.FM
icy-genre:Christmas Holiday Xmas
icy-url:http://www.christmas-channel.com
content-type:audio/mpeg
icy-pub:1
icy-metaint:32768
icy-br:192

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~Stop

and so on.

If I start the radio stream with BubbleUPnP then I can start and stop the stream within the Heos App. But I can't start a stream to begin with or change the radio stream. Nothing is showing in the log if I try to do so.