Skip to main content ↑ ↓

Notice

Please note that most of the software linked on this forum is likely to be safe to use. If you are unsure, feel free to ask in the relevant topics, or send a private message to an administrator or moderator. To help curb the problems of false positives, or in the event that you do find actual malware, you can contribute through the article linked here.
Topic: foo_upnp (Read 1646775 times) previous topic - next topic
0 Members and 1 Guest are viewing this topic.

foo_upnp

Reply #1100 –
Ok. I am experiencing the frequency problem once again. Here's the problem, though: I have not changed any settings. The only thing I did since my last post was stop and restart Foobar2000 a few times. I didn't mess with any settings.

Here is a picture of the profile settings:


And here is a snippet of the foobar2000 profile from the actual config file (to check for consistency):
Code: [Select]
<profile name="foobar2000" id="1">
        <http_headers names="User-Agent" substrings="foobar2000"></http_headers>
        <client_decode_http_stream>yes</client_decode_http_stream>
        <allow_submit_stats>yes</allow_submit_stats>
        <return_buggy_track_length>no</return_buggy_track_length>
        <mp3_transcode supported="yes">
          <mode except_exts="" except_types="">always</mode>
          <type>vbr</type>
          <vbr_quality>3</vbr_quality>
          <abr_cbr_bitrate>160</abr_cbr_bitrate>
        </mp3_transcode>
        <pcm_decode>
          <codec>lpcm</codec>
          <mode except_exts="">never</mode>
          <samplerate min="11025" max="192000"></samplerate>
          <convert_to_stereo>yes</convert_to_stereo>
          <max_bitdepth>24</max_bitdepth>
        </pcm_decode>
        <dsp_chain_conf>AAAAAA==</dsp_chain_conf>
        <playback_stream_capture codec="mp3" bitdepth="24" samplerate="44100"></playback_stream_capture>
        <replaygain source_mode="1" processing_mode="2" preamp_without_rg="0.000000" preamp_with_rg="0.000000" decoded_and_transcoded_only="no"></replaygain>
      </profile>

*UPDATE*

Right after I posted this, I stopped and restarted Foobar2000 (server end) once again, and now it works correctly again. I really have no clue why the behavior changes simply by restarting Foobar2000.

foo_upnp

Reply #1101 –
Good night bubbleguuum, and greetings from Portugal.

I've had a few problems with the UPnP plugin, on and off, which I wasn't able to discover, that is until today. So here it goes:

- when streaming from foobar to another client (usually from foobar in my tower pc to my laptop, either via lan or internet, but also to screamer radio), from time to time I'd get an constantly restarting stream, i.e. the client would start the stream, and after 1~3 seconds would restart (obviously I'd never hear anything) repeatedly.

This time I've troublshooted the problem: a fresh install of fb 1.1.2 on default settings, fresh UPnP plugin, pointed media library to my music, enabled streaming and started a stream to screamer radio (on the same pc).

I've found that, on the Playback settings (Activs DSP), by enabling 'convert stereo to 4 channels' will cause the streaming loop. If there are other DSP active, only by moving them above the 4 channels convertion will stop this behaviour.

The best way to test this is to use fb and screamer (or another client), enable internet acess on UPnP w/ mp3 strem, and then while streaming a file, go to the DSP page, remove all active DSPs and activate only 4 channels. This should trigger a loop, which will stop, and the stream will resume, when the DSPs disabled.

Is there a connection between foobar streaming profile and 'this' particular DSP?
Are there some settings I'm overlooking?
What could be causing this?

(Since I've found the "source" of my problem, I've tested it w/ mp3 streaming and this seems to be it. I use transcoding to 128kbps on internet streaming for bandwidth savings).

Thank you. And pardon my non-native bad english

foo_upnp

Reply #1102 –
Hello bubbleguuum,
thank you for this great plugin! I started using it some months ago and it does its jos very good. no complains. music plays from intranet and internet. perfect. :-)

I'd like to suggest to make it a little bit portable by adding something like an enviroment variable %COMPUTERNAME% (this is a windows variable) to the Network Name.

Reason:
I use foobar portable on more than one computer. (I make changes to a foobar instance and sync it with another foobar instance to have the same settings everywhere)

Because of this syncing, it is always the "same" foobar which is running on different machines and I always have the same Network Name in your settings.
If i want to connect i.e. with the iphone app "plugplayer" I do not know, which to use.

So it could be easier for the users with more than 1 instance of foobar running on their machines (imagine a big LAN in a household with 3 kids),
when you could add the possiblility to use a WINDOWS SYSTEM VARIABLE, like %COMPUTERNAME%.
So, the foobar instance running on each computer could automaticly use a Network Name like: Great Foobar UPNP on <%COMPUTERNAME%>

Any ideas to this?


foo_upnp

Reply #1103 –
Since Florian is not saying much about adding support for foo_upnp to foo_audioscrobbler, can we have scrobbler support in foo_upnp?
Thanks

foo_upnp

Reply #1104 –
Just to give an update, I can confirm that the resampling/speed issue continues to occur, but randomly. The temporary fix is to restart the Foobar2000 server instance until the sound is correct. This morning, I had to restart the Foobar2000 server four times before I got the sound correct on the client end.

My guess is that there is some kind of race condition upon initialization of the plugin and/or reading of the configuration file, but I have no way to tell as the source code is not available.

foo_upnp

Reply #1105 –
Quote from: chronomitch on
My guess is that there is some kind of race condition upon initialization of the plugin and/or reading of the configuration file
i must say, i never had a problem with the sound. i use upnp from foobar with a ps3, an iphone (plugplayer) and xbmc on an appleTV (or sometimes on windows). lucky me.
EDIT: foobar is running mostly under win7 and somtimes under XP.
 

foo_upnp

Reply #1106 –
I installed this to interface with my LG BD590 Blu-Ray player / media server, and this has been working reasonably well on my system for a while, with one exception. While the BD590 can access and play foobar2000 playlists fine (with the exception of FLAC files, which it chokes on), if I even try to access the streaming server the BD590 crashes and turns off. I would like to be able to dynamically change playlists on my PC to stream to the BD590 if possible. Any ideas?

foo_upnp

Reply #1107 –
Quote from: fuffi on
So it could be easier for the users with more than 1 instance of foobar running on their machines (imagine a big LAN in a household with 3 kids),
when you could add the possiblility to use a WINDOWS SYSTEM VARIABLE, like %COMPUTERNAME%.
So, the foobar instance running on each computer could automaticly use a Network Name like: Great Foobar UPNP on <%COMPUTERNAME%>


If it is a portable foobar2000 install, I'll automatically append %COMPUTERNAME% to the server name.


Quote from: vassie on
Since Florian is not saying much about adding support for foo_upnp to foo_audioscrobbler, can we have scrobbler support in foo_upnp?

No, I can't see myself implementing a scrobbling client.


Quote from: chronomitch on
My guess is that there is some kind of race condition upon initialization of the plugin and/or reading of the configuration file, but I have no way to tell as the source code is not available.


I still can't reproduce it and don't understand why this would happen. Maybe a sound card problem ?

Quote from: Mike Koenecke on
I installed this to interface with my LG BD590 Blu-Ray player / media server, and this has been working reasonably well on my system for a while, with one exception. While the BD590 can access and play foobar2000 playlists fine (with the exception of FLAC files, which it chokes on), if I even try to access the streaming server the BD590 crashes and turns off. I would like to be able to dynamically change playlists on my PC to stream to the BD590 if possible. Any ideas?


If your player is an Media Renderer it should appear in the UPnP Controller renderer list and then you can control it from foobar2000. Alternatively if it doesn't, you can use Playback Stream Capture to control it

foo_upnp

Reply #1108 –
Quote from: bubbleguuum on
If your player is an Media Renderer it should appear in the UPnP Controller renderer list and then you can control it from foobar2000. Alternatively if it doesn't, you can use Playback Stream Capture to control it


That's it: when I try to use Playback Stream Capture the LD BD590 crashes and shuts off. It's not a Media Renderer; I'm not trying to control it from foobar2000. I'm trying to stream audio (as opposed to just being able to play playlists with the BD590, which it does fine). I was wondering if there were some settings I need to be aware of to get this working properly.

foo_upnp

Reply #1109 –
Can someone put out a couple more clues about the xml file used for configuration of the browse tree?

Specifically,

1. The initial documentation had a query for "compilation PRESENT".  How would one format a query for "compilation NOT PRESENT"? How is the compilation flag set?
2. I see that it is possible to have a query like "genre IS jazz". What would be the format for the query: "genre IS NOT jazz"?

Thanks in advance for any help.

foo_upnp

Reply #1110 –
Read Query Syntax Help.html in your foobar install folder (or by going Library -> Search and hitting the question mark (?),) for the syntax used in queries.

foo_upnp

Reply #1112 –

I'm trying to get a WDTV Live Plus working consistently with foo_upnp.

My setup (everything is hard wired) works fine when the WDTV Live Plus is 'pulling' songs from a foo_upnp server (DMP mode). It works most of the time when the foo_upnp controller is used to 'push' songs to the WDTVLIVE (DMR mode). However, sometimes the setup gets into a mode where songs just stop playing after a couple of seconds. When the issue occurs, it happens when a song (or songs) is first sent to the WDTVLIVE. If the first song plays OK, then any subsequent songs play OK.

I have two log files: the first is an example of a successful play, and the second is an example of when the song ended abruptly.

It would be great if you could take a look -  I'd be glad to support the effort with any testing/logging you may require.


Code: [Select]
foo_upnp: 50.684: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Stopped
foo_upnp: 50.684: PLT_CtrlPoint::Subscribe(1441)/4816: INFO: Subscribing to service "urn:upnp-org:serviceId:ConnectionManager" of device "WDTVLIVE"
foo_upnp: 50.684: PLT_CtrlPoint::Subscribe(1441)/4816: INFO: Subscribing to service "urn:upnp-org:serviceId:RenderingControl" of device "WDTVLIVE"
foo_upnp: 50.684: RendererController::GetNearestResource(201)/4816: INFO: matching resource url to target ip 192.168.1.2: [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url]
foo_upnp: 50.684: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetTransportInfo
foo_upnp: 50.684: UPnPAVRendererController::SetAVTransportURIAction(507)/4816: INFO: CurrentURI = [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url], CurrentURIMetaData = <DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="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="1" parentID="0" restricted="1"><dc:title>01 - Jimi Hendrix - Hey Joe</dc:title><dc:creator>Jimi Hendrix</dc:creator><upnp:artist role="AlbumArtist">Jimi Hendrix</upnp:artist><upnp:author role="Composer">Familiar</upnp:author><upnp:album>Are You Experienced</upnp:album><upnp:genre>Rock-Classic</upnp:genre><upnp:genre>Rock</upnp:genre><dc:publisher>Mca</dc:publisher><upnp:albumArtURI>http://192.168.1.6:56923/albumart/2639055ddfd2e08faa63f6417cd4a07c</upnp:albumArtURI><upnp:longDescription> </upnp:longDescription><upnp:originalTrackNumber>1</upnp:originalTrackNumber><res duration="0:03:28.000" size="36691244" bitrate="176400" bitsPerSample="16" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/wav:*">http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
foo_upnp: 50.684: RendererController::InvokeActionSync(99)/4816: INFO: >>>>>>>>>>>>> action (sync) : SetAVTransportURI <<<<<<<<<<<<<<
foo_upnp: 50.684: PLT_CtrlPoint::ProcessSubscribeResponse(1517)/2168: INFO: Received subscription response for service "urn:upnp-org:serviceId:ConnectionManager" (result = 0)
foo_upnp: 50.684: PLT_CtrlPoint::ProcessSubscribeResponse(1540)/2168: INFO: Creating new subscriber "uuid:" for service "urn:upnp-org:serviceId:ConnectionManager" of device "WDTVLIVE"
foo_upnp: 50.684: PLT_StateVariable::ValidateValue(219)/1760: WARNING: Invalid value of 0 for state variable TransportPlaySpeed [allowed range { 1 }]
foo_upnp: 50.684: PLT_Argument::SetValue(130)/1760: SEVERE: NPT_CHECK failed, result=-20000 (NPT_ERROR_INVALID_PARAMETERS) [(ValidateValue(value))]
foo_upnp: 50.684: PLT_Argument::CreateArgument(107)/1760: WARNING: Invalid value of 0 for argument CurrentSpeed of action GetTransportInfo
foo_upnp: 50.684: PLT_Action::SetArgumentValue(222)/1760: SEVERE: NPT_CHECK failed, result=-20000 (NPT_ERROR_INVALID_PARAMETERS) [(PLT_Argument::CreateArgument(m_ActionDesc, name, value, arg))]
foo_upnp: 50.684: PLT_Action::VerifyArguments(270)/1760: WARNING: Argument CurrentSpeed for action GetTransportInfo not found
foo_upnp: 50.684: RendererController::OnActionResponse(127)/1760: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = -1
foo_upnp: 50.700: PLT_CtrlPoint::ProcessSubscribeResponse(1517)/580: INFO: Received subscription response for service "urn:upnp-org:serviceId:RenderingControl" (result = 0)
foo_upnp: 50.700: UPnPAVRendererController::play_track(776)/4816: INFO: Setting local playing item: file://C:\Users\Public\Music\Familiar\Jimi Hendrix - Are You Experienced - 01 - Hey Joe.mp3
foo_upnp: 50.700: RendererController::InvokeAction(88)/4816: INFO: >>>> CALL Play
foo_upnp: 50.700: UPnPAVRendererController::OnGetTransportInfoResponse(564)/4816: INFO: failure: InstanceID=0, CurrentTransportState=NO_MEDIA_PRESENT, CurrentTransportStatus=OK
foo_upnp: 50.700: UPnPAVRendererController::set_transport_state(213)/4816: INFO: No local playing item
foo_upnp: 50.700: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Stopped
foo_upnp: 50.700: UPnPAVRendererController::OnEventNotifyCallback(428)/4816: INFO: <<<< WDTVLIVE: EVENT SourceProtocolInfo=
foo_upnp: 50.700: UPnPAVRendererController::OnEventNotifyCallback(428)/4816: INFO: <<<< WDTVLIVE: EVENT SinkProtocolInfo=http-get:*:audio/L16;rate=44100;channels=1:DLNA.ORG_PN=LPCM,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3,http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMAFULL,http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMABASE,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_NTSC,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_NA,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_NA_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_NA_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_EU,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_EU_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_EU_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_KO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_KO_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_KO_ISO,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_PRO,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_BASE,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_FULL,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVHIGH_FULL,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVHIGH_PRO,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVHM_BASE,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVSPLL_BASE,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVSPML_BASE,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_SM,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_MED,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_TN,http-get:*:image/png:DLNA.ORG_PN=PNG_SM,http-get:*:image/png:DLNA.ORG_PN=PNG_MED,http-get:*:image/png:DLNA.ORG_PN=PNG_LRG,http-get:*:image/png:DLNA.ORG_PN=PNG_TN,http-get:*:audio/x-aiff:*,http-get:*:audio/x-wav:*,http-get:*:audio/lpcm:*,http-get:*:audio/x-mpegurl:*,http-get:*:audio/x-scpls:*,http-get:*:audio/x-ms-asx:*,http-get:*:audio/rmp:*,http-get:*:video/mpeg2:*,http-get:*:video/dvd:*,http-get:*:video/x-msvideo:*,http-get:*:video/avi:*,http-get:*:video/mp4:*,http-get:*:video/MP4V-ES:*,http-get:*:video/MP4V:*,http-get:*:video/x-mpegurl:*,http-get:*:video/x-scpls:*,http-get:*:video/x-ms-asx:*,http-get:*:video/rmp:*,http-get:*:image/tiff:*,http-get:*:image/jpeg:*,http-get:*:image/jpeg2000:*,http-get:*:image/png:*,http-get:*:image/bmp:*,http-get:*:image/gif:*,http-get:*:image/x-mpegurl:*,http-get:*:image/x-scpls:*,http-get:*:image/x-ms-asx:*,http-get:*:image/rmp:*,http-get:*:application/ogg:*,file:*:audio/x-ms-wma:*,file:*:audio/x-aiff:*,file:*:audio/mpeg:*,file:*:audio/x-wav:*,file:*:audio/lpcm:*,file:*:audio/x-mpegurl:*,file:*:audio/x-scpls:*,file:*:audio/x-ms-asx:*,file:*:audio/rmp:*,file:*:video/mpeg:*,file:*:video/mpeg2:*,file:*:video/dvd:*,file:*:video/x-msvideo:*,file:*:video/mp4:*,file:*:video/MP4V-ES:*,file:*:video/MP4V:*,file:*:video/x-mpegurl:*,file:*:video/x-scpls:*,file:*:video/x-ms-asx:*,file:*:video/rmp:*,file:*:image/tiff:*,file:*:image/jpeg:*,file:*:image/jpeg2000:*,file:*:image/png:*,file:*:image/bmp:*,file:*:image/gif:*,file:*:image/x-mpegurl:*,file:*:image/x-scpls:*,file:*:image/x-ms-asx:*,file:*:image/rmp:*,file:*:application/ogg:*,rhapsody:*:audio/x-ms-wma:*,rhapsody:*:audio/x-mpegurl:*,rhapsody:*:audio/x-rhap-radio:*,uvox:*:audio/mpeg:*,http-get:*:image/sls:*,http-get:*:application/x-dtcp1;CONTENTFORMAT=image/jpeg:*,unicast:*:video/mpeg2ts:*,multicast:*:video/mpeg2ts:*,rtsp:*:video/mp4:*,rtsp:*:misc/sdp:*
foo_upnp: 50.700: UPnPAVRendererController::OnEventNotifyCallback(428)/4816: INFO: <<<< WDTVLIVE: EVENT CurrentConnectionIDs=0
foo_upnp: 50.715: RendererController::OnActionResponse(127)/5776: INFO: <<<< WDTVLIVE: RET Play: res = 0
foo_upnp: 51.714: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetTransportInfo
foo_upnp: 51.729: PLT_StateVariable::ValidateValue(219)/4768: WARNING: Invalid value of 0 for state variable TransportPlaySpeed [allowed range { 1 }]
foo_upnp: 51.729: PLT_Argument::SetValue(130)/4768: SEVERE: NPT_CHECK failed, result=-20000 (NPT_ERROR_INVALID_PARAMETERS) [(ValidateValue(value))]
foo_upnp: 51.729: PLT_Argument::CreateArgument(107)/4768: WARNING: Invalid value of 0 for argument CurrentSpeed of action GetTransportInfo
foo_upnp: 51.729: PLT_Action::SetArgumentValue(222)/4768: SEVERE: NPT_CHECK failed, result=-20000 (NPT_ERROR_INVALID_PARAMETERS) [(PLT_Argument::CreateArgument(m_ActionDesc, name, value, arg))]
foo_upnp: 51.729: PLT_Action::VerifyArguments(270)/4768: WARNING: Argument CurrentSpeed for action GetTransportInfo not found
foo_upnp: 51.729: RendererController::OnActionResponse(127)/4768: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = -1
foo_upnp: 51.729: UPnPAVRendererController::OnGetTransportInfoResponse(564)/4816: INFO: failure: InstanceID=0, CurrentTransportState=TRANSITIONING, CurrentTransportStatus=OK
foo_upnp: 51.729: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Buffering...
foo_upnp: 52.556: FbMediaServer::ProcessHttpRequest(306)/2680: INFO: received LAN request: [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url] (User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks)
foo_upnp: 52.556: PLT_HttpHelper::ToLog(410)/2680: INFO: 
HEAD /content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav HTTP/1.0
Host: 192.168.1.6:56923
getcontentFeatures.dlna.org: 1
transferMode.dlna.org: Streaming
User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
foo_upnp: opened 'file://C:\Users\Public\Music\Familiar\Jimi Hendrix - Are You Experienced - 01 - Hey Joe.mp3' for streaming, profile: Default, WAV / 44100 Hz / 16 bits / 2 channels, replaygain: on, DSP chain: off
foo_upnp: 52.572: FbMediaServer::ProcessHttpRequest(306)/6052: INFO: received LAN request: [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url] (User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks)
foo_upnp: 52.572: PLT_HttpHelper::ToLog(410)/6052: INFO: 
GET /content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav HTTP/1.0
Host: 192.168.1.6:56923
Content-Length: 0
transferMode.dlna.org: Streaming
User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
foo_upnp: opened 'file://C:\Users\Public\Music\Familiar\Jimi Hendrix - Are You Experienced - 01 - Hey Joe.mp3' for streaming, profile: Default, WAV / 44100 Hz / 16 bits / 2 channels, replaygain: on, DSP chain: off
foo_upnp: 52.681: PLT_HttpServerSocketTask::Write(348)/6052: WARNING: NPT_CHECK failed, result=-20401 (NPT_ERROR_CONNECTION_ABORTED) [(NPT_StreamToStreamCopy( *body_stream.AsPointer(), *output_stream.AsPointer(), 0, entity->GetContentLength(), 0, abort))]
foo_upnp: 52.712: FbMediaServer::ProcessHttpRequest(306)/5656: INFO: received LAN request: [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url] (User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks)
foo_upnp: 52.712: PLT_HttpHelper::ToLog(410)/5656: INFO: 
GET /content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav HTTP/1.0
Host: 192.168.1.6:56923
Content-Length: 0
transferMode.dlna.org: Streaming
User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
foo_upnp: opened 'file://C:\Users\Public\Music\Familiar\Jimi Hendrix - Are You Experienced - 01 - Hey Joe.mp3' for streaming, profile: Default, WAV / 44100 Hz / 16 bits / 2 channels, replaygain: on, DSP chain: off
foo_upnp: 52.743: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetTransportInfo
foo_upnp: 52.759: PLT_StateVariable::ValidateValue(219)/1684: WARNING: Invalid value of 0 for state variable TransportPlaySpeed [allowed range { 1 }]
foo_upnp: 52.774: PLT_Argument::SetValue(130)/1684: SEVERE: NPT_CHECK failed, result=-20000 (NPT_ERROR_INVALID_PARAMETERS) [(ValidateValue(value))]
foo_upnp: 52.774: PLT_Argument::CreateArgument(107)/1684: WARNING: Invalid value of 0 for argument CurrentSpeed of action GetTransportInfo
foo_upnp: 52.774: PLT_Action::SetArgumentValue(222)/1684: SEVERE: NPT_CHECK failed, result=-20000 (NPT_ERROR_INVALID_PARAMETERS) [(PLT_Argument::CreateArgument(m_ActionDesc, name, value, arg))]
foo_upnp: 52.774: PLT_Action::VerifyArguments(270)/1684: WARNING: Argument CurrentSpeed for action GetTransportInfo not found
foo_upnp: 52.774: RendererController::OnActionResponse(127)/1684: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = -1
foo_upnp: 52.790: UPnPAVRendererController::OnGetTransportInfoResponse(564)/4816: INFO: failure: InstanceID=0, CurrentTransportState=TRANSITIONING, CurrentTransportStatus=OK
foo_upnp: 53.742: PLT_CtrlPoint::ProcessHttpNotify(689)/4656: SEVERE: uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:RenderingControl, callback_uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:ConnectionManager, NT=upnp:event, NTS=upnp:propchange
foo_upnp: 53.742: PLT_CtrlPoint::ProcessHttpNotify(690)/4656: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [((-1))]
foo_upnp: 53.742: PLT_CtrlPoint::ProcessHttpNotify(750)/4656: SEVERE: CtrlPoint received bad request
foo_upnp: 53.804: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetTransportInfo
foo_upnp: 53.820: RendererController::OnActionResponse(127)/4160: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 54.303: PLT_CtrlPoint::ProcessHttpNotify(689)/952: SEVERE: uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:RenderingControl, callback_uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:ConnectionManager, NT=upnp:event, NTS=upnp:propchange
foo_upnp: 54.303: PLT_CtrlPoint::ProcessHttpNotify(690)/952: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [((-1))]
foo_upnp: 54.303: PLT_CtrlPoint::ProcessHttpNotify(750)/952: SEVERE: CtrlPoint received bad request
foo_upnp: 54.834: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 54.849: RendererController::OnActionResponse(127)/2736: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 55.863: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 55.863: RendererController::OnActionResponse(127)/636: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 55.863: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 55.863: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 
foo_upnp: 55.879: RendererController::OnActionResponse(127)/2324: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 55.879: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 56.877: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 56.877: RendererController::OnActionResponse(127)/3544: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 56.893: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 56.893: RendererController::OnActionResponse(127)/3460: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 56.908: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 57.891: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 57.907: RendererController::OnActionResponse(127)/1800: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 57.922: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 57.922: RendererController::OnActionResponse(127)/4600: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 57.922: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 58.921: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 58.921: RendererController::OnActionResponse(127)/2712: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 58.936: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 58.952: RendererController::OnActionResponse(127)/4508: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 58.952: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 59.482: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 1
foo_upnp: 59.514: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 2
foo_upnp: 59.529: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 3
foo_upnp: 59.545: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 5
foo_upnp: 59.560: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 7
foo_upnp: 59.576: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 9
foo_upnp: 59.576: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 11
foo_upnp: 59.592: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 13
foo_upnp: 59.716: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 14
foo_upnp: 59.826: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 15
foo_upnp: 59.857: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 16
foo_upnp: 59.857: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 16
foo_upnp: 59.857: RendererController::InvokeAction(88)/4816: INFO: >>>> CALL SetVolume
foo_upnp: 59.872: RendererController::OnActionResponse(127)/4148: INFO: <<<< WDTVLIVE: RET SetVolume: res = 0
foo_upnp: 59.872: PLT_CtrlPoint::ProcessHttpNotify(689)/3032: SEVERE: uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:RenderingControl, callback_uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:ConnectionManager, NT=upnp:event, NTS=upnp:propchange
foo_upnp: 59.872: PLT_CtrlPoint::ProcessHttpNotify(690)/3032: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [((-1))]
foo_upnp: 59.872: PLT_CtrlPoint::ProcessHttpNotify(750)/3032: SEVERE: CtrlPoint received bad request
foo_upnp: 59.935: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 59.966: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 59.966: RendererController::OnActionResponse(127)/4488: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 59.966: RendererController::OnActionResponse(127)/5224: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 60.699: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 17
foo_upnp: 60.777: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 18
foo_upnp: 60.777: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 19
foo_upnp: 60.793: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 20
foo_upnp: 60.808: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 22
foo_upnp: 60.840: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 23
foo_upnp: 60.855: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 26
foo_upnp: 60.871: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 28
foo_upnp: 60.871: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 31
foo_upnp: 60.886: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 32
foo_upnp: 60.886: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 33
foo_upnp: 60.933: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 34
foo_upnp: 60.980: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 60.980: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetTransportInfo
foo_upnp: 60.996: RendererController::OnActionResponse(127)/5200: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 60.996: RendererController::OnActionResponse(127)/5552: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 61.074: controller_window::set_track_info_string(1028)/4816: INFO: track info display: Volume 34
foo_upnp: 61.074: RendererController::InvokeAction(88)/4816: INFO: >>>> CALL SetVolume
foo_upnp: 61.074: RendererController::OnActionResponse(127)/5976: INFO: <<<< WDTVLIVE: RET SetVolume: res = 0
foo_upnp: 61.089: PLT_CtrlPoint::ProcessHttpNotify(689)/5496: SEVERE: uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:RenderingControl, callback_uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:ConnectionManager, NT=upnp:event, NTS=upnp:propchange
foo_upnp: 61.089: PLT_CtrlPoint::ProcessHttpNotify(690)/5496: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [((-1))]
foo_upnp: 61.089: PLT_CtrlPoint::ProcessHttpNotify(750)/5496: SEVERE: CtrlPoint received bad request
foo_upnp: 62.010: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 62.010: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetPositionInfo
foo_upnp: 62.025: RendererController::OnActionResponse(127)/4884: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 62.025: RendererController::OnActionResponse(127)/4480: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 62.587: controller_window::set_track_info_string(1028)/2476: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 63.039: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 63.039: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetPositionInfo
foo_upnp: 63.039: RendererController::OnActionResponse(127)/2432: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 63.055: RendererController::OnActionResponse(127)/4844: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 63.055: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 64.053: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetTransportInfo
foo_upnp: 64.053: RendererController::OnActionResponse(127)/4820: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 64.069: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 64.069: RendererController::OnActionResponse(127)/3260: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 64.069: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 65.067: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 65.067: RendererController::OnActionResponse(127)/3564: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 65.083: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 65.083: RendererController::OnActionResponse(127)/1292: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 65.083: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 66.081: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 66.097: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 66.097: RendererController::OnActionResponse(127)/4336: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 66.112: RendererController::OnActionResponse(127)/5208: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 66.112: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 67.111: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 67.111: RendererController::OnActionResponse(127)/4308: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 67.126: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 67.158: RendererController::OnActionResponse(127)/4860: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 67.158: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 68.172: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetPositionInfo
foo_upnp: 68.172: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetTransportInfo
foo_upnp: 68.187: RendererController::OnActionResponse(127)/404: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0
foo_upnp: 68.187: controller_window::set_track_info_string(1028)/4816: INFO: track info display: 01 - Jimi Hendrix - Hey Joe
foo_upnp: 68.187: RendererController::OnActionResponse(127)/3648: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 69.201: RendererController::InvokeAction(88)/2476: INFO: >>>> CALL GetTransportInfo
foo_upnp: 69.201: RendererController::InvokeAction(88)/4956: INFO: >>>> CALL GetPositionInfo
foo_upnp: 69.217: RendererController::OnActionResponse(127)/2744: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
Shutting down...
foo_upnp: 69.295: RendererController::OnActionResponse(127)/5872: INFO: <<<< WDTVLIVE: RET GetPositionInfo: res = 0

Code: [Select]
foo_upnp: 112.115: controller_window::set_track_info_string(1028)/568: INFO: track info display: Stopped
foo_upnp: 112.115: PLT_CtrlPoint::Subscribe(1441)/568: INFO: Subscribing to service "urn:upnp-org:serviceId:ConnectionManager" of device "WDTVLIVE"
foo_upnp: 112.115: PLT_CtrlPoint::Subscribe(1441)/568: INFO: Subscribing to service "urn:upnp-org:serviceId:RenderingControl" of device "WDTVLIVE"
foo_upnp: 112.115: RendererController::InvokeAction(88)/1764: INFO: >>>> CALL GetTransportInfo
foo_upnp: 112.115: RendererController::GetNearestResource(201)/568: INFO: matching resource url to target ip 192.168.1.2: [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url]
foo_upnp: 112.115: UPnPAVRendererController::SetAVTransportURIAction(507)/568: INFO: CurrentURI = [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url], CurrentURIMetaData = <DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="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="1" parentID="0" restricted="1"><dc:title>01 - Jimi Hendrix - Hey Joe</dc:title><dc:creator>Jimi Hendrix</dc:creator><upnp:artist role="AlbumArtist">Jimi Hendrix</upnp:artist><upnp:author role="Composer">Familiar</upnp:author><upnp:album>Are You Experienced</upnp:album><upnp:genre>Rock-Classic</upnp:genre><upnp:genre>Rock</upnp:genre><dc:publisher>Mca</dc:publisher><upnp:albumArtURI>http://192.168.1.6:56923/albumart/2639055ddfd2e08faa63f6417cd4a07c</upnp:albumArtURI><upnp:longDescription> </upnp:longDescription><upnp:originalTrackNumber>1</upnp:originalTrackNumber><res duration="0:03:28.000" size="36691244" bitrate="176400" bitsPerSample="16" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/wav:*">http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
foo_upnp: 112.115: RendererController::InvokeActionSync(99)/568: INFO: >>>>>>>>>>>>> action (sync) : SetAVTransportURI <<<<<<<<<<<<<<
foo_upnp: 112.130: PLT_CtrlPoint::ProcessSubscribeResponse(1517)/5016: INFO: Received subscription response for service "urn:upnp-org:serviceId:ConnectionManager" (result = 0)
foo_upnp: 112.130: PLT_CtrlPoint::ProcessSubscribeResponse(1540)/5016: INFO: Creating new subscriber "uuid:" for service "urn:upnp-org:serviceId:ConnectionManager" of device "WDTVLIVE"
foo_upnp: 112.130: PLT_CtrlPoint::ProcessSubscribeResponse(1517)/3800: INFO: Received subscription response for service "urn:upnp-org:serviceId:RenderingControl" (result = 0)
foo_upnp: 112.130: RendererController::OnActionResponse(127)/5968: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 112.130: UPnPAVRendererController::play_track(776)/568: INFO: Setting local playing item: file://C:\Users\Public\Music\Familiar\Jimi Hendrix - Are You Experienced - 01 - Hey Joe.mp3
foo_upnp: 112.130: RendererController::InvokeAction(88)/568: INFO: >>>> CALL Play
foo_upnp: 112.130: UPnPAVRendererController::set_transport_state(213)/568: INFO: No local playing item
foo_upnp: 112.130: controller_window::set_track_info_string(1028)/568: INFO: track info display: Stopped
foo_upnp: 112.146: UPnPAVRendererController::OnEventNotifyCallback(428)/568: INFO: <<<< WDTVLIVE: EVENT SourceProtocolInfo=
foo_upnp: 112.146: UPnPAVRendererController::OnEventNotifyCallback(428)/568: INFO: <<<< WDTVLIVE: EVENT SinkProtocolInfo=http-get:*:audio/L16;rate=44100;channels=1:DLNA.ORG_PN=LPCM,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3,http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMAFULL,http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMABASE,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_NTSC,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_NA,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_NA_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_NA_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_EU,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_EU_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_EU_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_KO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_KO_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_KO_ISO,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_PRO,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_BASE,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_FULL,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVHIGH_FULL,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVHIGH_PRO,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVHM_BASE,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVSPLL_BASE,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVSPML_BASE,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_SM,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_MED,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_TN,http-get:*:image/png:DLNA.ORG_PN=PNG_SM,http-get:*:image/png:DLNA.ORG_PN=PNG_MED,http-get:*:image/png:DLNA.ORG_PN=PNG_LRG,http-get:*:image/png:DLNA.ORG_PN=PNG_TN,http-get:*:audio/x-aiff:*,http-get:*:audio/x-wav:*,http-get:*:audio/lpcm:*,http-get:*:audio/x-mpegurl:*,http-get:*:audio/x-scpls:*,http-get:*:audio/x-ms-asx:*,http-get:*:audio/rmp:*,http-get:*:video/mpeg2:*,http-get:*:video/dvd:*,http-get:*:video/x-msvideo:*,http-get:*:video/avi:*,http-get:*:video/mp4:*,http-get:*:video/MP4V-ES:*,http-get:*:video/MP4V:*,http-get:*:video/x-mpegurl:*,http-get:*:video/x-scpls:*,http-get:*:video/x-ms-asx:*,http-get:*:video/rmp:*,http-get:*:image/tiff:*,http-get:*:image/jpeg:*,http-get:*:image/jpeg2000:*,http-get:*:image/png:*,http-get:*:image/bmp:*,http-get:*:image/gif:*,http-get:*:image/x-mpegurl:*,http-get:*:image/x-scpls:*,http-get:*:image/x-ms-asx:*,http-get:*:image/rmp:*,http-get:*:application/ogg:*,file:*:audio/x-ms-wma:*,file:*:audio/x-aiff:*,file:*:audio/mpeg:*,file:*:audio/x-wav:*,file:*:audio/lpcm:*,file:*:audio/x-mpegurl:*,file:*:audio/x-scpls:*,file:*:audio/x-ms-asx:*,file:*:audio/rmp:*,file:*:video/mpeg:*,file:*:video/mpeg2:*,file:*:video/dvd:*,file:*:video/x-msvideo:*,file:*:video/mp4:*,file:*:video/MP4V-ES:*,file:*:video/MP4V:*,file:*:video/x-mpegurl:*,file:*:video/x-scpls:*,file:*:video/x-ms-asx:*,file:*:video/rmp:*,file:*:image/tiff:*,file:*:image/jpeg:*,file:*:image/jpeg2000:*,file:*:image/png:*,file:*:image/bmp:*,file:*:image/gif:*,file:*:image/x-mpegurl:*,file:*:image/x-scpls:*,file:*:image/x-ms-asx:*,file:*:image/rmp:*,file:*:application/ogg:*,rhapsody:*:audio/x-ms-wma:*,rhapsody:*:audio/x-mpegurl:*,rhapsody:*:audio/x-rhap-radio:*,uvox:*:audio/mpeg:*,http-get:*:image/sls:*,http-get:*:application/x-dtcp1;CONTENTFORMAT=image/jpeg:*,unicast:*:video/mpeg2ts:*,multicast:*:video/mpeg2ts:*,rtsp:*:video/mp4:*,rtsp:*:misc/sdp:*
foo_upnp: 112.146: UPnPAVRendererController::OnEventNotifyCallback(428)/568: INFO: <<<< WDTVLIVE: EVENT CurrentConnectionIDs=0
foo_upnp: 112.146: RendererController::OnActionResponse(127)/800: INFO: <<<< WDTVLIVE: RET Play: res = 0
foo_upnp: 112.255: FbMediaServer::ProcessHttpRequest(306)/4704: INFO: received LAN request: [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url] (User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks)
foo_upnp: 112.255: PLT_HttpHelper::ToLog(410)/4704: INFO: 
HEAD /content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav HTTP/1.0
Host: 192.168.1.6:56923
getcontentFeatures.dlna.org: 1
transferMode.dlna.org: Streaming
User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
foo_upnp: opened 'file://C:\Users\Public\Music\Familiar\Jimi Hendrix - Are You Experienced - 01 - Hey Joe.mp3' for streaming, profile: Default, WAV / 44100 Hz / 16 bits / 2 channels, replaygain: on, DSP chain: off
foo_upnp: 112.286: FbMediaServer::ProcessHttpRequest(306)/5880: INFO: received LAN request: [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url] (User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks)
foo_upnp: 112.286: PLT_HttpHelper::ToLog(410)/5880: INFO: 
GET /content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav HTTP/1.0
Host: 192.168.1.6:56923
Content-Length: 0
transferMode.dlna.org: Streaming
User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
foo_upnp: opened 'file://C:\Users\Public\Music\Familiar\Jimi Hendrix - Are You Experienced - 01 - Hey Joe.mp3' for streaming, profile: Default, WAV / 44100 Hz / 16 bits / 2 channels, replaygain: on, DSP chain: off
foo_upnp: 112.302: PLT_HttpServerSocketTask::Write(348)/5880: WARNING: NPT_CHECK failed, result=-20401 (NPT_ERROR_CONNECTION_ABORTED) [(NPT_StreamToStreamCopy( *body_stream.AsPointer(), *output_stream.AsPointer(), 0, entity->GetContentLength(), 0, abort))]
foo_upnp: 112.333: FbMediaServer::ProcessHttpRequest(306)/3904: INFO: received LAN request: [url=http://192.168.1.6:56923/content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav]http://192.168.1.6:56923/content/2639055dd...amp;convert=wav[/url] (User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks)
foo_upnp: 112.349: PLT_HttpHelper::ToLog(410)/3904: INFO: 
GET /content/2639055ddfd2e08faa63f6417cd4a07c.wav?profile_id=0&amp;convert=wav HTTP/1.0
Host: 192.168.1.6:56923
Content-Length: 0
transferMode.dlna.org: Streaming
User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
foo_upnp: opened 'file://C:\Users\Public\Music\Familiar\Jimi Hendrix - Are You Experienced - 01 - Hey Joe.mp3' for streaming, profile: Default, WAV / 44100 Hz / 16 bits / 2 channels, replaygain: on, DSP chain: off
foo_upnp: 113.144: RendererController::InvokeAction(88)/1764: INFO: >>>> CALL GetTransportInfo
foo_upnp: 113.160: RendererController::OnActionResponse(127)/2624: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = 0
foo_upnp: 113.160: controller_window::set_track_info_string(1028)/568: INFO: track info display: Buffering...
foo_upnp: 113.409: PLT_CtrlPoint::ProcessHttpNotify(689)/4132: SEVERE: uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:RenderingControl, callback_uri=/a469a1b4-0090-a99d-21f0-62f55adc4205/urn:upnp-org:serviceId:ConnectionManager, NT=upnp:event, NTS=upnp:propchange
foo_upnp: 113.409: PLT_CtrlPoint::ProcessHttpNotify(690)/4132: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [((-1))]
foo_upnp: 113.409: PLT_CtrlPoint::ProcessHttpNotify(750)/4132: SEVERE: CtrlPoint received bad request
foo_upnp: 114.174: RendererController::InvokeAction(88)/1764: INFO: >>>> CALL GetTransportInfo
foo_upnp: 115.172: PLT_HttpTcpConnector::Connect(86)/5760: FATAL: NPT_CHECK failed, result=-20402 (NPT_ERROR_CONNECTION_REFUSED) [(m_Socket->Connect(socket_address, connection_timeout))]
foo_upnp: 115.172: NPT_HttpClient::SendRequestOnce(1070)/5760: WARNING: NPT_CHECK failed, result=-20402 (NPT_ERROR_CONNECTION_REFUSED) [(m_Connector->Connect(server_hostname, server_port, m_Config.m_ConnectionTimeout, m_Config.m_IoTimeout, m_Config.m_NameResolverTimeout, input_stream, output_stream))]
foo_upnp: 115.172: RendererController::OnActionResponse(127)/5760: INFO: <<<< WDTVLIVE: RET GetTransportInfo: res = -20402
foo_upnp: 115.172: UPnPAVRendererController::OnGetTransportInfoResponse(564)/568: INFO: failure: InstanceID=0
foo_upnp: 115.172: UPnPAVRendererController::OnGetTransportInfoResponse(578)/568: INFO: failure: InstanceID=0
foo_upnp: 116.607: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.607: PLT_CtrlPoint::Subscribe(1466)/568: INFO: Unsubscribing subscriber "uuid:" for service "urn:upnp-org:serviceId:ConnectionManager" of device "WDTVLIVE"
foo_upnp: 116.607: PLT_CtrlPoint::Subscribe(1466)/568: INFO: Unsubscribing subscriber "unknown" for service "urn:upnp-org:serviceId:RenderingControl" of device "WDTVLIVE"
foo_upnp: 116.607: PLT_CtrlPoint::Subscribe(1466)/568: INFO: Unsubscribing subscriber "unknown" for service "urn:upnp-org:serviceId:ConnectionManager" of device "WDTVLIVE"
foo_upnp: 116.607: PLT_CtrlPoint::Subscribe(1466)/568: INFO: Unsubscribing subscriber "unknown" for service "urn:upnp-org:serviceId:RenderingControl" of device "WDTVLIVE"
foo_upnp: 116.607: controller_window::set_track_info_string(1028)/568: INFO: track info display: 
foo_upnp: 116.607: PLT_HttpServerSocketTask::Write(348)/3904: WARNING: NPT_CHECK failed, result=-20401 (NPT_ERROR_CONNECTION_ABORTED) [(NPT_StreamToStreamCopy( *body_stream.AsPointer(), *output_stream.AsPointer(), 0, entity->GetContentLength(), 0, abort))]
foo_upnp: 116.607: PLT_CtrlPoint::CleanupDevice(1004)/4904: INFO: Removing a469a1b4-0090-a99d-21f0-62f55adc4205 from device list
foo_upnp: 116.607: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.607: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.623: PLT_CtrlPoint::ProcessSsdpNotify(905)/4904: INFO: Received a byebye NOTIFY request from a469a1b4-0090-a99d-21f0-62f55adc4205
foo_upnp: 116.732: PLT_CtrlPoint::InspectDevice(1104)/4904: INFO: New device "a469a1b4-0090-a99d-21f0-62f55adc4205" detected @ [url=http://192.168.1.2:56045/]http://192.168.1.2:56045/[/url]
foo_upnp: 117.621: PLT_HttpTcpConnector::Connect(86)/4396: FATAL: NPT_CHECK failed, result=-20402 (NPT_ERROR_CONNECTION_REFUSED) [(m_Socket->Connect(socket_address, connection_timeout))]
foo_upnp: 117.621: NPT_HttpClient::SendRequestOnce(1070)/4396: WARNING: NPT_CHECK failed, result=-20402 (NPT_ERROR_CONNECTION_REFUSED) [(m_Connector->Connect(server_hostname, server_port, m_Config.m_ConnectionTimeout, m_Config.m_IoTimeout, m_Config.m_NameResolverTimeout, input_stream, output_stream))]
foo_upnp: 117.621: PLT_CtrlPoint::ProcessSubscribeResponse(1517)/4396: INFO: Received subscription response for service "urn:upnp-org:serviceId:ConnectionManager" (result = -20402)
foo_upnp: 117.621: PLT_CtrlPoint::ProcessSubscribeResponse(1523)/4396: SEVERE: NPT_CHECK failed, result=-20402 (NPT_ERROR_CONNECTION_REFUSED) [(((res) != 0)?res:(-1))]
foo_upnp: 117.621: PLT_CtrlPoint::ProcessSubscribeResponse(1556)/4396: SEVERE: (un)subscription failed of sub "?" for service "urn:upnp-org:serviceId:ConnectionManager" of device "WDTVLIVE", code=-1
foo_upnp: 117.824: PLT_CtrlPoint::ProcessGetDescriptionResponse(1179)/5740: INFO: Received device description for a469a1b4-0090-a99d-21f0-62f55adc4205 (result = 0)
foo_upnp: 117.840: PLT_CtrlPoint::ProcessGetDescriptionResponse(1234)/5740: INFO: Device "a469a1b4-0090-a99d-21f0-62f55adc4205" is now known as "WDTVLIVE"
foo_upnp: 117.840: PLT_AddGetSCPDRequestIterator::operator ()(142)/5740: INFO: Queueing SCPD request for service "urn:upnp-org:serviceId:AVTransport" of device "WDTVLIVE" @ [url=http://192.168.1.2:56045/MediaRenderer_AVTransport/scpd.xml]http://192.168.1.2:56045/MediaRenderer_AVTransport/scpd.xml[/url]
foo_upnp: 117.840: PLT_AddGetSCPDRequestIterator::operator ()(142)/5740: INFO: Queueing SCPD request for service "urn:upnp-org:serviceId:ConnectionManager" of device "WDTVLIVE" @ [url=http://192.168.1.2:56045/MediaRenderer_ConnectionManager/scpd.xml]http://192.168.1.2:56045/MediaRenderer_Con...anager/scpd.xml[/url]
foo_upnp: 117.840: PLT_AddGetSCPDRequestIterator::operator ()(142)/5740: INFO: Queueing SCPD request for service "urn:upnp-org:serviceId:RenderingControl" of device "WDTVLIVE" @ [url=http://192.168.1.2:56045/MediaRenderer_RenderingControl/scpd.xml]http://192.168.1.2:56045/MediaRenderer_Ren...ontrol/scpd.xml[/url]
foo_upnp: 117.949: PLT_CtrlPoint::ProcessGetSCPDResponse(1300)/4520: INFO: Received SCPD response for a service of device "WDTVLIVE" @ [url=http://192.168.1.2:56045/MediaRenderer_AVTransport/scpd.xml]http://192.168.1.2:56045/MediaRenderer_AVTransport/scpd.xml[/url] (result = 0)
foo_upnp: 117.949: PLT_CtrlPoint::ProcessGetSCPDResponse(1300)/4520: INFO: Received SCPD response for a service of device "WDTVLIVE" @ [url=http://192.168.1.2:56045/MediaRenderer_ConnectionManager/scpd.xml]http://192.168.1.2:56045/MediaRenderer_Con...anager/scpd.xml[/url] (result = 0)
foo_upnp: 117.949: PLT_CtrlPoint::ProcessGetSCPDResponse(1300)/4520: INFO: Received SCPD response for a service of device "WDTVLIVE" @ [url=http://192.168.1.2:56045/MediaRenderer_RenderingControl/scpd.xml]http://192.168.1.2:56045/MediaRenderer_Ren...ontrol/scpd.xml[/url] (result = 0)
foo_upnp: 117.949: LinnDsRendererController::Create(117)/568: INFO: couldn't find urn:linn-co-uk:service:Product:3 service
foo_upnp: 117.949: RendererController::Create(53)/568: INFO: Created UPnPAv Controller for device: WDTVLIVE
Shutting down...

foo_upnp

Reply #1113 –
Hello

I am using foobar and the UpNp pluging to listen music on my hifi (Naim Uniti). Works very well. However, there are some files wich cannot be read with foobar : for examples, music from youtube (FLV files) or from spotify. So it would be very good if the UpNp player could also be connected to capture the PC sound output and redirect it direcly to the Uniti. This is possible with Asset-UpNp, as has been explained on the Naim forum (http://forums.naimaudio.com/displayForumTopic/content/2974496135321583). But I like foobar very much, and would prefer to stay with it.

Do you think it is possible ?

Thanks again

Philippe

foo_upnp

Reply #1114 –
For the Record, I can confirm that an Oppo BDP-83 will playback 96 khz, 24 bit 5.0 files with the latest firmware (Feb 2, 2011) using Foobar's UPNP plugin (and no DSPs in the chain).

Problems with this scenario:
1.  Oppo BDP-83 chokes on all 5.1 files (in ear screeching fashion). 
2.  Foobar's problem is that UPNP streaming profile's 'stereo only' is not exactly an audiophiles dream.

Potential 'bug fix'
Why not replace Foobar's streaming profiles 'stereo only' checkbox with a 'channel output' drop down that includes 2.0, 4.0, 5.0, 2.1, 4.1, 6.1.  This change is required as other plugins like 'Channel Mixer' will internally reassign/mix channels, but they do not actually change the LPCM output.  Foobar will output the files 'native' channel count, or will remux to 'stereo' but will not allow any other channel combination.

Maybe this isn't seen as a Foobar 'bug'...until you listen to an Oppo BDP-83 attempt to play a 5.1 channel file when it can only play 5.0 LPCM over UPNP.  Think fingernails on chalkboard...bad stuff.  In any event, this is aberrant behavior so I think of it as a 'playback chain' bug...and something Foobar could fix for this and other playback chain problems.

Obviously something is screwed up in Oppo's channel assignments.  Now that the product has been discontinued, one wonders whether if Oppo will ever make the effort to fix this.  And of course, why should I be forced to buy an Oppo BDP-93 just to get 3 more channels to output with UPNP? 

For users who don't have a subwoofer and instead have full range Front Left and Front Right speakers, 5.0 is OK.  Other users have 2.1, 4.1, 4.0 setups, etc.  Most folks have some sort of component in between their amplifier and foobar.  These components sometimes have weird channel assignments and configuration issues (like the BDP-83).

Foobar seems to be a perfect complement to an Oppo BDP-83.  There's legions of Oppo fanatics trying to get surround to work. They are paying money to use Asset for UPNP, but of course it's nowhere close to having the broad support of Foobar.

Here's a forum of frustrated Oppo users looking for multichannel UPNP love...and one post that sums things up Oppo support for 24 bit 96 khz? 
Notice that they're just trying to get Asset UPNP to work in stereo, of course Foobar can do 96/24 for files with 5.0 channels...so how about calming these people by making any file work in surround (sans subwoofer of course).

Another thing...can we combine Foobar with Firefox?
I would donate $100 if Foobar was open sourced (and maybe more if we could start using it with some of our own systems). I'd really like to see Foobar applied as a audio renderer for a browser.  Foobar+Firefox would go so nice with HTML 5's <audio> tag capabilities.  Imagine playing back DVD-A/FLAC files in surround with your browser...total GUI freedom.

Quote from: dmytty on
For the Record, I can confirm that an Oppo BDP-83 will playback 96 khz, 24 bit 5.0 files with the latest firmware (Feb 2, 2011) using Foobar's UPNP plugin (and no DSPs in the chain).


I can further state that 96/24 Surround 4.0 LPCM over Foobar UPnP is also supported on the Oppo BDP-83.

This is significant as many audiophile systems are either 4.0 or 5.0 configurations. 

If Foobar supported a dropdown 'channel output' with options of 2.0, 4.0, 5.0 and 5.1, this would bring a significant number of Oppo users into the Foobar fold.

Even for 5.1 system setups, it would be better to have surround (and miss the LFE channel) than not have surround file playback over UPnP.

Wouldn't this be as simple as dropping the LFE LPCM channels when outputting the UPnP stream?

foo_upnp

Reply #1115 –
Quote from: dmytty on
For the Record, I can confirm that an Oppo BDP-83 will playback 96 khz, 24 bit 5.0 files with the latest firmware (Feb 2, 2011) using Foobar's UPNP plugin (and no DSPs in the chain).


This is not a UPnP issue.  UPnP components don't write LPCM headers.

foo_upnp

Reply #1116 –
I'm trying to stream to my LG TV but it is having trouble connecting to Foobar:uPnP and display a list of files. Would the log below help to isolate what the problem is? It looks like it's having trouble with a upnp:class call.

192.168.1.20 is where Foobar is running; 192.168.1.28 is the TV.


Thanks

Code: [Select]
19255.859: PLT_DeviceHost::SendSsdpSearchResponse(756)/2244: FINEST: Responding to a M-SEARCH request for urn:schemas-upnp-org:device:MediaServer:1
19255.859: PLT_SsdpSender::SendSsdp(103)/2244: FINE: Sending SSDP:
19255.859: PLT_HttpHelper::ToLog(496)/2244: FINE: 
HTTP/1.1 200 OK
LOCATION: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url]
CACHE-CONTROL: max-age=1800
Server: UPnP/1.0, DLNADOC/1.50, Platinum/0.5.4.0 / foobar2000
EXT: 
USN: uuid:42adc705-71cb-b393-8af2-391829a1bb7d::urn:schemas-upnp-org:device:MediaServer:1
ST: urn:schemas-upnp-org:device:MediaServer:1

19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header LOCATION: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url]
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header CACHE-CONTROL: max-age=1800
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header Server: UPnP/1.0, DLNADOC/1.50, Platinum/0.5.4.0 / foobar2000
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header EXT: 
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header USN: uuid:42adc705-71cb-b393-8af2-391829a1bb7d::urn:schemas-upnp-org:device:MediaServer:1
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header ST: urn:schemas-upnp-org:device:MediaServer:1
19255.859: PLT_DeviceHost::SendSsdpSearchResponse(756)/2244: FINEST: Responding to a M-SEARCH request for urn:schemas-upnp-org:device:MediaServer:1
19255.859: PLT_SsdpSender::SendSsdp(103)/2244: FINE: Sending SSDP:
19255.859: PLT_HttpHelper::ToLog(496)/2244: FINE: 
HTTP/1.1 200 OK
LOCATION: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url]
CACHE-CONTROL: max-age=1800
Server: UPnP/1.0, DLNADOC/1.50, Platinum/0.5.4.0 / foobar2000
EXT: 
USN: uuid:42adc705-71cb-b393-8af2-391829a1bb7d::urn:schemas-upnp-org:device:MediaServer:1
ST: urn:schemas-upnp-org:device:MediaServer:1

19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header LOCATION: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url]
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header CACHE-CONTROL: max-age=1800
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header Server: UPnP/1.0, DLNADOC/1.50, Platinum/0.5.4.0 / foobar2000
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header EXT: 
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header USN: uuid:42adc705-71cb-b393-8af2-391829a1bb7d::urn:schemas-upnp-org:device:MediaServer:1
19255.859: NPT_HttpHeader::Emit(118)/2244: FINEST: header ST: urn:schemas-upnp-org:device:MediaServer:1
19255.859: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19255.859: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19255.859: NPT_Win32Thread::EntryPoint(450)/3912: FINER: thread in =======================
19255.859: NPT_HttpRequest::Parse(617)/3912: FINEST: http request: GET /DeviceDescription.xml HTTP/1.1
19255.859: NPT_HttpHeaders::Parse(185)/3912: FINEST: header - HOST: 192.168.1.20:56923
19255.859: NPT_HttpHeaders::Parse(185)/3912: FINEST: header - DATE: Thu, 01 Jan 1970 00:39:50 GMT
19255.859: NPT_HttpHeaders::Parse(185)/3912: FINEST: header - USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
19255.859: NPT_HttpHeaders::Parse(215)/3912: FINEST: header friendlyName.dlna.org: LG DLNA DMP DEVICE
19255.859: PLT_HttpServer::ProcessHttpRequest(142)/3912: FINE: Received GET Request from 192.168.1.28 for /DeviceDescription.xml
19255.859: PLT_HttpHelper::ToLog(410)/3912: FINER: 
GET /DeviceDescription.xml HTTP/1.1
HOST: 192.168.1.20:56923
DATE: Thu, 01 Jan 1970 00:39:50 GMT
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
friendlyName.dlna.org: LG DLNA DMP DEVICE

19255.859: FbMediaServer::ProcessHttpRequest(306)/3912: INFO: received LAN request: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url] (User-Agent: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0)
19255.859: PLT_DeviceHost::ProcessHttpRequest(376)/3912: FINER: PLT_DeviceHost Received Request:
19255.859: PLT_HttpHelper::ToLog(410)/3912: FINER: 
GET /DeviceDescription.xml HTTP/1.1
HOST: 192.168.1.20:56923
DATE: Thu, 01 Jan 1970 00:39:50 GMT
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
friendlyName.dlna.org: LG DLNA DMP DEVICE

19255.859: FbMediaServer::ProcessGetDescription(375)/3912: INFO: ProcessGetDescription: user-agent: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
19255.875: PLT_DeviceHost::ProcessGetDescription(422)/3912: FINEST: Returning description to 192.168.1.28: <?xml version="1.0" encoding="UTF-8"?>
<root xmlns="urn:schemas-upnp-org:device-1-0" xmlns:dlna="urn:schemas-dlna-org:device-1-0"><specVersion><major>1</major><minor>0</minor></specVersion><device><deviceType>urn:schemas-upnp-org:device:MediaServer:1</deviceType><friendlyName>foobar2000 Media Server (klui@dc7100)</friendlyName><manufacturer>Michael Pujos</manufacturer><manufacturerURL>http://www.hydrogenaudio.org/forums/index.php?showtopic=69664</manufacturerURL><modelDescription>foobar2000 Media Server</modelDescription><modelName>foobar2000 Media Server</modelName><modelURL>http://www.hydrogenaudio.org/forums/index.php?showtopic=69664</modelURL><modelNumber>0.99.29</modelNumber><serialNumber></serialNumber><UDN>uuid:42adc705-71cb-b393-8af2-391829a1bb7d</UDN><dlna:X_DLNADOC xmlns:dlna="urn:schemas-dlna-org:device-1-0">DMS-1.50</dlna:X_DLNADOC><iconList><icon><mimetype>image/jpeg</mimetype><width>48</width><height>48</height><depth>24</depth><url>/images/foobar2000-48x48.jpg</url></icon><icon><mimetype>image/png</mimetype><width>48</width><height>48</height><depth>32</depth><url>/images/foobar2000-48x48.png</url></icon></iconList><serviceList><service><serviceType>urn:schemas-upnp-org:service:ContentDirectory:1</serviceType><serviceId>urn:upnp-org:serviceId:ContentDirectory</serviceId><SCPDURL>/ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml</SCPDURL><controlURL>/ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml</controlURL><eventSubURL>/ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml</eventSubURL></service><service><serviceType>urn:schemas-upnp-org:service:ConnectionManager:1</serviceType><serviceId>urn:upnp-org:serviceId:ConnectionManager</serviceId><SCPDURL>/ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml</SCPDURL><controlURL>/ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml</controlURL><eventSubURL>/ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml</eventSubURL></service><service><serviceType>urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1</serviceType><serviceId>urn:microsoft.com:serviceId:X_MS_MediaReceiverRegistrar</serviceId><SCPDURL>/X_MS_MediaReceiverRegistrar/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml</SCPDURL><controlURL>/X_MS_MediaReceiverRegistrar/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml</controlURL><eventSubURL>/X_MS_MediaReceiverRegistrar/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml</eventSubURL></service></serviceList></device></root>
19255.875: PLT_HttpServerSocketTask::Write(322)/3912: FINER: PLT_HttpServerTask Sending response:
19255.875: PLT_HttpHelper::ToLog(496)/3912: FINER: 
HTTP/1.1 200 OK
Server: Platinum/0.5.4.0
Content-Length: 2510
Content-Type: text/xml; charset="utf-8"
Connection: keep-alive

19255.875: NPT_HttpHeader::Emit(118)/3912: FINEST: header Server: Platinum/0.5.4.0
19255.875: NPT_HttpHeader::Emit(118)/3912: FINEST: header Content-Length: 2510
19255.875: NPT_HttpHeader::Emit(118)/3912: FINEST: header Content-Type: text/xml; charset="utf-8"
19255.875: NPT_HttpHeader::Emit(118)/3912: FINEST: header Connection: keep-alive
19255.875: PLT_HttpServerSocketTask::Write(336)/3912: FINER: keep_alive = true, headers_only = false
19255.875: PLT_HttpServerSocketTask::Write(341)/3912: FINER: NPT_StreamToStreamCopy begin
19255.875: PLT_HttpServerSocketTask::Write(349)/3912: FINER: NPT_StreamToStreamCopy end
19255.890: NPT_HttpRequest::Parse(616)/3912: FINE: NPT_CHECK failed, result=-20302 (NPT_ERROR_EOS) [(stream.ReadLine(line, NPT_HTTP_PROTOCOL_MAX_LINE_LENGTH))]
19255.890: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19255.890: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19255.890: NPT_Win32Thread::EntryPoint(450)/2556: FINER: thread in =======================
19255.890: NPT_HttpRequest::Parse(617)/2556: FINEST: http request: GET /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml HTTP/1.1
19255.890: NPT_HttpHeaders::Parse(185)/2556: FINEST: header - HOST: 192.168.1.20:56923
19255.890: NPT_HttpHeaders::Parse(185)/2556: FINEST: header - DATE: Thu, 01 Jan 1970 00:39:50 GMT
19255.890: NPT_HttpHeaders::Parse(185)/2556: FINEST: header - USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
19255.890: NPT_HttpHeaders::Parse(215)/2556: FINEST: header friendlyName.dlna.org: LG DLNA DMP DEVICE
19255.890: PLT_HttpServer::ProcessHttpRequest(142)/2556: FINE: Received GET Request from 192.168.1.28 for /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml
19255.890: PLT_HttpHelper::ToLog(410)/2556: FINER: 
GET /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml HTTP/1.1
HOST: 192.168.1.20:56923
DATE: Thu, 01 Jan 1970 00:39:50 GMT
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
friendlyName.dlna.org: LG DLNA DMP DEVICE

19255.890: PLT_HttpServerSocketTask::Write(322)/2556: FINER: PLT_HttpServerTask Sending response:
19255.890: PLT_HttpHelper::ToLog(496)/2556: FINER: 
HTTP/1.1 200 OK
Server: Platinum/0.5.4.0
Connection: keep-alive
Content-Length: 5117
Content-Type: text/xml; charset="utf-8"

19255.890: NPT_HttpHeader::Emit(118)/2556: FINEST: header Server: Platinum/0.5.4.0
19255.890: NPT_HttpHeader::Emit(118)/2556: FINEST: header Connection: keep-alive
19255.890: NPT_HttpHeader::Emit(118)/2556: FINEST: header Content-Length: 5117
19255.890: NPT_HttpHeader::Emit(118)/2556: FINEST: header Content-Type: text/xml; charset="utf-8"
19255.890: PLT_HttpServerSocketTask::Write(336)/2556: FINER: keep_alive = true, headers_only = false
19255.906: PLT_HttpServerSocketTask::Write(341)/2556: FINER: NPT_StreamToStreamCopy begin
19255.906: PLT_HttpServerSocketTask::Write(349)/2556: FINER: NPT_StreamToStreamCopy end
19255.953: NPT_HttpRequest::Parse(616)/2556: FINE: NPT_CHECK failed, result=-20302 (NPT_ERROR_EOS) [(stream.ReadLine(line, NPT_HTTP_PROTOCOL_MAX_LINE_LENGTH))]
19255.953: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19255.953: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19255.953: NPT_Win32Thread::EntryPoint(450)/2080: FINER: thread in =======================
19255.969: NPT_HttpRequest::Parse(617)/2080: FINEST: http request: GET /ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml HTTP/1.1
19255.969: NPT_HttpHeaders::Parse(185)/2080: FINEST: header - HOST: 192.168.1.20:56923
19255.969: NPT_HttpHeaders::Parse(185)/2080: FINEST: header - DATE: Thu, 01 Jan 1970 00:39:50 GMT
19255.969: NPT_HttpHeaders::Parse(185)/2080: FINEST: header - USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
19255.969: NPT_HttpHeaders::Parse(215)/2080: FINEST: header friendlyName.dlna.org: LG DLNA DMP DEVICE
19255.969: PLT_HttpServer::ProcessHttpRequest(142)/2080: FINE: Received GET Request from 192.168.1.28 for /ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml
19255.969: PLT_HttpHelper::ToLog(410)/2080: FINER: 
GET /ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/scpd.xml HTTP/1.1
HOST: 192.168.1.20:56923
DATE: Thu, 01 Jan 1970 00:39:50 GMT
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
friendlyName.dlna.org: LG DLNA DMP DEVICE

19255.969: PLT_HttpServerSocketTask::Write(322)/2080: FINER: PLT_HttpServerTask Sending response:
19255.969: PLT_HttpHelper::ToLog(496)/2080: FINER: 
HTTP/1.1 200 OK
Server: Platinum/0.5.4.0
Connection: keep-alive
Content-Length: 3396
Content-Type: text/xml; charset="utf-8"

19255.969: NPT_HttpHeader::Emit(118)/2080: FINEST: header Server: Platinum/0.5.4.0
19255.969: NPT_HttpHeader::Emit(118)/2080: FINEST: header Connection: keep-alive
19255.969: NPT_HttpHeader::Emit(118)/2080: FINEST: header Content-Length: 3396
19255.969: NPT_HttpHeader::Emit(118)/2080: FINEST: header Content-Type: text/xml; charset="utf-8"
19255.969: PLT_HttpServerSocketTask::Write(336)/2080: FINER: keep_alive = true, headers_only = false
19255.969: PLT_HttpServerSocketTask::Write(341)/2080: FINER: NPT_StreamToStreamCopy begin
19255.969: PLT_HttpServerSocketTask::Write(349)/2080: FINER: NPT_StreamToStreamCopy end
19256.344: NPT_HttpRequest::Parse(617)/3312: FINEST: http request: M-SEARCH * HTTP/1.1
19256.344: NPT_HttpHeaders::Parse(185)/3312: FINEST: header - HOST: 239.255.255.250:1900
19256.344: NPT_HttpHeaders::Parse(185)/3312: FINEST: header - MAN: "ssdp:discover"
19256.344: NPT_HttpHeaders::Parse(185)/3312: FINEST: header - MX: 7
19256.344: NPT_HttpHeaders::Parse(215)/3312: FINEST: header ST: urn:schemas-upnp-org:device:MediaServer:1
19256.344: PLT_DeviceHost::ProcessSsdpSearchRequest(719)/3312: FINEST: Received M-SEARCH from 192.168.1.28:54375
19256.344: PLT_HttpHelper::ToLog(410)/3312: FINE: 
M-SEARCH * HTTP/1.1
HOST: 239.255.255.250:1900
MAN: "ssdp:discover"
MX: 7
ST: urn:schemas-upnp-org:device:MediaServer:1

19256.344: NPT_Win32Thread::Start(483)/3312: FINER: creating thread
19256.344: NPT_Win32Thread::EntryPoint(450)/3020: FINER: thread in =======================
19262.390: PLT_DeviceHost::SendSsdpSearchResponse(756)/3020: FINEST: Responding to a M-SEARCH request for urn:schemas-upnp-org:device:MediaServer:1
19262.390: PLT_SsdpSender::SendSsdp(103)/3020: FINE: Sending SSDP:
19262.390: PLT_HttpHelper::ToLog(496)/3020: FINE: 
HTTP/1.1 200 OK
LOCATION: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url]
CACHE-CONTROL: max-age=1800
Server: UPnP/1.0, DLNADOC/1.50, Platinum/0.5.4.0 / foobar2000
EXT: 
USN: uuid:42adc705-71cb-b393-8af2-391829a1bb7d::urn:schemas-upnp-org:device:MediaServer:1
ST: urn:schemas-upnp-org:device:MediaServer:1

19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header LOCATION: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url]
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header CACHE-CONTROL: max-age=1800
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header Server: UPnP/1.0, DLNADOC/1.50, Platinum/0.5.4.0 / foobar2000
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header EXT: 
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header USN: uuid:42adc705-71cb-b393-8af2-391829a1bb7d::urn:schemas-upnp-org:device:MediaServer:1
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header ST: urn:schemas-upnp-org:device:MediaServer:1
19262.390: PLT_DeviceHost::SendSsdpSearchResponse(756)/3020: FINEST: Responding to a M-SEARCH request for urn:schemas-upnp-org:device:MediaServer:1
19262.390: PLT_SsdpSender::SendSsdp(103)/3020: FINE: Sending SSDP:
19262.390: PLT_HttpHelper::ToLog(496)/3020: FINE: 
HTTP/1.1 200 OK
LOCATION: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url]
CACHE-CONTROL: max-age=1800
Server: UPnP/1.0, DLNADOC/1.50, Platinum/0.5.4.0 / foobar2000
EXT: 
USN: uuid:42adc705-71cb-b393-8af2-391829a1bb7d::urn:schemas-upnp-org:device:MediaServer:1
ST: urn:schemas-upnp-org:device:MediaServer:1

19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header LOCATION: [url=http://192.168.1.20:56923/DeviceDescription.xml]http://192.168.1.20:56923/DeviceDescription.xml[/url]
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header CACHE-CONTROL: max-age=1800
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header Server: UPnP/1.0, DLNADOC/1.50, Platinum/0.5.4.0 / foobar2000
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header EXT: 
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header USN: uuid:42adc705-71cb-b393-8af2-391829a1bb7d::urn:schemas-upnp-org:device:MediaServer:1
19262.390: NPT_HttpHeader::Emit(118)/3020: FINEST: header ST: urn:schemas-upnp-org:device:MediaServer:1
19285.844: NPT_HttpRequest::Parse(616)/2080: FINE: NPT_CHECK failed, result=-20302 (NPT_ERROR_EOS) [(stream.ReadLine(line, NPT_HTTP_PROTOCOL_MAX_LINE_LENGTH))]
19304.078: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19304.078: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19304.078: NPT_Win32Thread::EntryPoint(450)/1800: FINER: thread in =======================
19304.078: NPT_HttpRequest::Parse(617)/1800: FINEST: http request: SUBSCRIBE /ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml HTTP/1.1
19304.078: NPT_HttpHeaders::Parse(185)/1800: FINEST: header - HOST: 192.168.1.20:56923
19304.078: NPT_HttpHeaders::Parse(185)/1800: FINEST: header - CALLBACK: <http://192.168.1.28:35341/>
19304.078: NPT_HttpHeaders::Parse(185)/1800: FINEST: header - NT: upnp:event
19304.078: NPT_HttpHeaders::Parse(215)/1800: FINEST: header TIMEOUT: Second-1800
19304.078: PLT_HttpServer::ProcessHttpRequest(142)/1800: FINE: Received SUBSCRIBE Request from 192.168.1.28 for /ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml
19304.078: PLT_HttpHelper::ToLog(410)/1800: FINER: 
SUBSCRIBE /ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml HTTP/1.1
HOST: 192.168.1.20:56923
CALLBACK: <http://192.168.1.28:35341/>
NT: upnp:event
TIMEOUT: Second-1800

19304.078: FbMediaServer::ProcessHttpRequest(306)/1800: INFO: received LAN request: [url=http://192.168.1.20:56923/ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml]http://192.168.1.20:56923/ConnectionManage...1bb7d/event.xml[/url] (User-Agent: Unknown)
19304.078: PLT_DeviceHost::ProcessHttpRequest(376)/1800: FINER: PLT_DeviceHost Received Request:
19304.078: PLT_HttpHelper::ToLog(410)/1800: FINER: 
SUBSCRIBE /ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml HTTP/1.1
HOST: 192.168.1.20:56923
CALLBACK: <http://192.168.1.28:35341/>
NT: upnp:event
TIMEOUT: Second-1800

19304.078: PLT_Service::ProcessNewSubscription(506)/1800: FINE: New subscription for ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml
19304.078: PLT_Service::ProcessNewSubscription(534)/1800: INFO: New subscription for ConnectionManager/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml, sid=uuid:7e2f8f8e-c40f-c8a4-52d6-ece7e41ac206
19304.078: PLT_EventSubscriber::PLT_EventSubscriber(60)/1800: FINE: Creating new subscriber (uuid:7e2f8f8e-c40f-c8a4-52d6-ece7e41ac206)
19304.078: PLT_EventSubscriber::SetTimeout(141)/1800: FINE: subscriber (uuid:7e2f8f8e-c40f-c8a4-52d6-ece7e41ac206) expiring in 1800 seconds
19304.078: PLT_EventSubscriber::AddCallbackURL(176)/1800: FINE: Adding callback "http://192.168.1.28:35341/" to subscriber uuid:7e2f8f8e-c40f-c8a4-52d6-ece7e41ac206
19304.078: NPT_Win32Thread::Start(483)/1800: FINER: creating thread
19304.078: PLT_HttpServerSocketTask::Write(322)/1800: FINER: PLT_HttpServerTask Sending response:
19304.078: PLT_HttpHelper::ToLog(496)/1800: FINER: 
HTTP/1.1 200 OK
Server: Platinum/0.5.4.0
SID: uuid:7e2f8f8e-c40f-c8a4-52d6-ece7e41ac206
TIMEOUT: Second-1800
Content-Length: 0
Connection: keep-alive

19304.078: NPT_HttpHeader::Emit(118)/1800: FINEST: header Server: Platinum/0.5.4.0
19304.078: NPT_HttpHeader::Emit(118)/1800: FINEST: header SID: uuid:7e2f8f8e-c40f-c8a4-52d6-ece7e41ac206
19304.078: NPT_HttpHeader::Emit(118)/1800: FINEST: header TIMEOUT: Second-1800
19304.078: NPT_HttpHeader::Emit(118)/1800: FINEST: header Content-Length: 0
19304.078: NPT_HttpHeader::Emit(118)/1800: FINEST: header Connection: keep-alive
19304.078: PLT_HttpServerSocketTask::Write(336)/1800: FINER: keep_alive = true, headers_only = false
19304.078: NPT_Win32Thread::EntryPoint(450)/1440: FINER: thread in =======================
19304.078: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19304.078: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19304.078: NPT_Win32Thread::EntryPoint(450)/2660: FINER: thread in =======================
19304.078: NPT_HttpRequest::Parse(617)/2660: FINEST: http request: SUBSCRIBE /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml HTTP/1.1
19304.078: NPT_HttpHeaders::Parse(185)/2660: FINEST: header - HOST: 192.168.1.20:56923
19304.078: NPT_HttpHeaders::Parse(185)/2660: FINEST: header - CALLBACK: <http://192.168.1.28:35341/>
19304.078: NPT_HttpHeaders::Parse(185)/2660: FINEST: header - NT: upnp:event
19304.078: NPT_HttpHeaders::Parse(215)/2660: FINEST: header TIMEOUT: Second-1800
19304.078: PLT_HttpServer::ProcessHttpRequest(142)/2660: FINE: Received SUBSCRIBE Request from 192.168.1.28 for /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml
19304.078: PLT_HttpHelper::ToLog(410)/2660: FINER: 
SUBSCRIBE /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml HTTP/1.1
HOST: 192.168.1.20:56923
CALLBACK: <http://192.168.1.28:35341/>
NT: upnp:event
TIMEOUT: Second-1800

19304.078: FbMediaServer::ProcessHttpRequest(306)/2660: INFO: received LAN request: [url=http://192.168.1.20:56923/ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml]http://192.168.1.20:56923/ContentDirectory...1bb7d/event.xml[/url] (User-Agent: Unknown)
19304.078: PLT_DeviceHost::ProcessHttpRequest(376)/2660: FINER: PLT_DeviceHost Received Request:
19304.078: PLT_HttpHelper::ToLog(410)/2660: FINER: 
SUBSCRIBE /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml HTTP/1.1
HOST: 192.168.1.20:56923
CALLBACK: <http://192.168.1.28:35341/>
NT: upnp:event
TIMEOUT: Second-1800

19304.078: PLT_Service::ProcessNewSubscription(506)/2660: FINE: New subscription for ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml
19304.078: PLT_Service::ProcessNewSubscription(534)/2660: INFO: New subscription for ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/event.xml, sid=uuid:0b61ebcd-ca17-586a-7f06-53d1cc9b86c6
19304.078: PLT_EventSubscriber::PLT_EventSubscriber(60)/2660: FINE: Creating new subscriber (uuid:0b61ebcd-ca17-586a-7f06-53d1cc9b86c6)
19304.078: PLT_EventSubscriber::SetTimeout(141)/2660: FINE: subscriber (uuid:0b61ebcd-ca17-586a-7f06-53d1cc9b86c6) expiring in 1800 seconds
19304.078: PLT_EventSubscriber::AddCallbackURL(176)/2660: FINE: Adding callback "http://192.168.1.28:35341/" to subscriber uuid:0b61ebcd-ca17-586a-7f06-53d1cc9b86c6
19304.078: NPT_Win32Thread::Start(483)/2660: FINER: creating thread
19304.078: PLT_HttpServerSocketTask::Write(322)/2660: FINER: PLT_HttpServerTask Sending response:
19304.078: PLT_HttpHelper::ToLog(496)/2660: FINER: 
HTTP/1.1 200 OK
Server: Platinum/0.5.4.0
SID: uuid:0b61ebcd-ca17-586a-7f06-53d1cc9b86c6
TIMEOUT: Second-1800
Content-Length: 0
Connection: keep-alive

19304.078: NPT_HttpHeader::Emit(118)/2660: FINEST: header Server: Platinum/0.5.4.0
19304.078: NPT_HttpHeader::Emit(118)/2660: FINEST: header SID: uuid:0b61ebcd-ca17-586a-7f06-53d1cc9b86c6
19304.078: NPT_HttpHeader::Emit(118)/2660: FINEST: header TIMEOUT: Second-1800
19304.078: NPT_HttpHeader::Emit(118)/2660: FINEST: header Content-Length: 0
19304.078: NPT_Win32Thread::EntryPoint(450)/3168: FINER: thread in =======================
19304.078: NPT_HttpHeader::Emit(118)/2660: FINEST: header Connection: keep-alive
19304.078: PLT_HttpServerSocketTask::Write(336)/2660: FINER: keep_alive = true, headers_only = false
19304.094: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19304.094: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19304.094: NPT_Win32Thread::EntryPoint(450)/3128: FINER: thread in =======================
19304.094: NPT_HttpRequest::Parse(617)/3128: FINEST: http request: POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
19304.094: NPT_HttpHeaders::Parse(185)/3128: FINEST: header - HOST: 192.168.1.20:56923
19304.094: NPT_HttpHeaders::Parse(185)/3128: FINEST: header - CONTENT-LENGTH: 638
19304.094: NPT_HttpHeaders::Parse(185)/3128: FINEST: header - CONTENT-TYPE: text/xml; charset="utf-8"
19304.094: NPT_HttpHeaders::Parse(185)/3128: FINEST: header - SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
19304.094: NPT_HttpHeaders::Parse(215)/3128: FINEST: header USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
19304.094: PLT_HttpServer::ProcessHttpRequest(142)/3128: FINE: Received POST Request from 192.168.1.28 for /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml
19304.094: PLT_HttpHelper::ToLog(410)/3128: FINER: 
POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
HOST: 192.168.1.20:56923
CONTENT-LENGTH: 638
CONTENT-TYPE: text/xml; charset="utf-8"
SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0

19304.094: FbMediaServer::ProcessHttpRequest(306)/3128: INFO: received LAN request: [url=http://192.168.1.20:56923/ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml]http://192.168.1.20:56923/ContentDirectory...b7d/control.xml[/url] (User-Agent: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0)
19304.094: PLT_DeviceHost::ProcessHttpRequest(376)/3128: FINER: PLT_DeviceHost Received Request:
19304.094: PLT_HttpHelper::ToLog(410)/3128: FINER: 
POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
HOST: 192.168.1.20:56923
CONTENT-LENGTH: 638
CONTENT-TYPE: text/xml; charset="utf-8"
SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0

19304.094: PLT_DeviceHost::ProcessHttpPostRequest(555)/3128: FINE: Received Browse action from 192.168.1.28
19304.094: FbMediaServer::OnAction(243)/3128: INFO: received action 'Browse', args = { ObjectID=0, BrowseFlag=BrowseMetadata, Filter=@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resol
ution,res@protocolInfo,res@nrAudioChannels,
res@sampleFrequency, StartingIndex=0, RequestedCount=0, SortCriteria= }
19304.094: PLT_MediaServer::OnBrowse(380)/3128: INFO: Received BrowseMetadata from 192.168.1.28 for id = 0 with filter = @id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resol
ution,res@protocolInfo,res@nrAudioChannels,
res@sampleFrequency, start = 0, count = 0
19304.094: FbMediaServer::OnAction(260)/3128: INFO: action 'Browse' response: args = { ObjectID=0, BrowseFlag=BrowseMetadata, Filter=@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resol
ution,res@protocolInfo,res@nrAudioChannels,
res@sampleFrequency, StartingIndex=0, RequestedCount=0, SortCriteria=, NumberReturned=1, TotalMatches=1, UpdateID=0 }
19304.094: PLT_HttpServerSocketTask::Write(322)/3128: FINER: PLT_HttpServerTask Sending response:
19304.094: PLT_HttpHelper::ToLog(496)/3128: FINER: 
HTTP/1.1 200 OK
Server: Platinum/0.5.4.0
Content-Type: text/xml; charset="utf-8"
Ext: 
Connection: keep-alive
Content-Length: 871

19304.094: NPT_HttpHeader::Emit(118)/3128: FINEST: header Server: Platinum/0.5.4.0
19304.094: NPT_HttpHeader::Emit(118)/3128: FINEST: header Content-Type: text/xml; charset="utf-8"
19304.094: NPT_HttpHeader::Emit(118)/3128: FINEST: header Ext: 
19304.094: NPT_HttpHeader::Emit(118)/3128: FINEST: header Connection: keep-alive
19304.094: NPT_HttpHeader::Emit(118)/3128: FINEST: header Content-Length: 871
19304.094: PLT_HttpServerSocketTask::Write(336)/3128: FINER: keep_alive = true, headers_only = false
19304.094: PLT_HttpServerSocketTask::Write(341)/3128: FINER: NPT_StreamToStreamCopy begin
19304.094: PLT_HttpServerSocketTask::Write(349)/3128: FINER: NPT_StreamToStreamCopy end
19304.109: NPT_HttpRequest::Parse(616)/3128: FINE: NPT_CHECK failed, result=-20302 (NPT_ERROR_EOS) [(stream.ReadLine(line, NPT_HTTP_PROTOCOL_MAX_LINE_LENGTH))]
19304.109: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19304.109: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19304.109: NPT_Win32Thread::EntryPoint(450)/3396: FINER: thread in =======================
19304.109: NPT_HttpRequest::Parse(617)/3396: FINEST: http request: POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
19304.109: NPT_HttpHeaders::Parse(185)/3396: FINEST: header - HOST: 192.168.1.20:56923
19304.109: NPT_HttpHeaders::Parse(185)/3396: FINEST: header - CONTENT-LENGTH: 425
19304.109: NPT_HttpHeaders::Parse(185)/3396: FINEST: header - CONTENT-TYPE: text/xml; charset="utf-8"
19304.109: NPT_HttpHeaders::Parse(185)/3396: FINEST: header - SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
19304.109: NPT_HttpHeaders::Parse(215)/3396: FINEST: header USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
19304.109: PLT_HttpServer::ProcessHttpRequest(142)/3396: FINE: Received POST Request from 192.168.1.28 for /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml
19304.109: PLT_HttpHelper::ToLog(410)/3396: FINER: 
POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
HOST: 192.168.1.20:56923
CONTENT-LENGTH: 425
CONTENT-TYPE: text/xml; charset="utf-8"
SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0

19304.109: FbMediaServer::ProcessHttpRequest(306)/3396: INFO: received LAN request: [url=http://192.168.1.20:56923/ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml]http://192.168.1.20:56923/ContentDirectory...b7d/control.xml[/url] (User-Agent: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0)
19304.109: PLT_DeviceHost::ProcessHttpRequest(376)/3396: FINER: PLT_DeviceHost Received Request:
19304.109: PLT_HttpHelper::ToLog(410)/3396: FINER: 
POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
HOST: 192.168.1.20:56923
CONTENT-LENGTH: 425
CONTENT-TYPE: text/xml; charset="utf-8"
SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0

19304.109: PLT_DeviceHost::ProcessHttpPostRequest(555)/3396: FINE: Received Browse action from 192.168.1.28
19304.109: FbMediaServer::OnAction(243)/3396: INFO: received action 'Browse', args = { ObjectID=0, BrowseFlag=BrowseDirectChildren, Filter=*, StartingIndex=0, RequestedCount=10, SortCriteria= }
19304.109: PLT_MediaServer::OnBrowse(380)/3396: INFO: Received BrowseDirectChildren from 192.168.1.28 for id = 0 with filter = *, start = 0, count = 10
19304.109: FbMediaServer::OnAction(260)/3396: INFO: action 'Browse' response: args = { ObjectID=0, BrowseFlag=BrowseDirectChildren, Filter=*, StartingIndex=0, RequestedCount=10, SortCriteria=, NumberReturned=3, TotalMatches=3, UpdateID=0 }
19304.109: PLT_HttpServerSocketTask::Write(322)/3396: FINER: PLT_HttpServerTask Sending response:
19304.109: PLT_HttpHelper::ToLog(496)/3396: FINER: 
HTTP/1.1 200 OK
Server: Platinum/0.5.4.0
Content-Type: text/xml; charset="utf-8"
Ext: 
Connection: keep-alive
Content-Length: 1452

19304.109: NPT_HttpHeader::Emit(118)/3396: FINEST: header Server: Platinum/0.5.4.0
19304.109: NPT_HttpHeader::Emit(118)/3396: FINEST: header Content-Type: text/xml; charset="utf-8"
19304.109: NPT_HttpHeader::Emit(118)/3396: FINEST: header Ext: 
19304.109: NPT_HttpHeader::Emit(118)/3396: FINEST: header Connection: keep-alive
19304.109: NPT_HttpHeader::Emit(118)/3396: FINEST: header Content-Length: 1452
19304.109: PLT_HttpServerSocketTask::Write(336)/3396: FINER: keep_alive = true, headers_only = false
19304.109: PLT_HttpServerSocketTask::Write(341)/3396: FINER: NPT_StreamToStreamCopy begin
19304.109: PLT_HttpServerSocketTask::Write(349)/3396: FINER: NPT_StreamToStreamCopy end
19304.125: NPT_HttpRequest::Parse(616)/3396: FINE: NPT_CHECK failed, result=-20302 (NPT_ERROR_EOS) [(stream.ReadLine(line, NPT_HTTP_PROTOCOL_MAX_LINE_LENGTH))]
19304.125: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19304.125: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19304.125: NPT_Win32Thread::EntryPoint(450)/676: FINER: thread in =======================
19304.125: NPT_HttpRequest::Parse(617)/676: FINEST: http request: POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
19304.125: NPT_HttpHeaders::Parse(185)/676: FINEST: header - HOST: 192.168.1.20:56923
19304.125: NPT_HttpHeaders::Parse(185)/676: FINEST: header - CONTENT-LENGTH: 645
19304.125: NPT_HttpHeaders::Parse(185)/676: FINEST: header - CONTENT-TYPE: text/xml; charset="utf-8"
19304.125: NPT_HttpHeaders::Parse(185)/676: FINEST: header - SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
19304.125: NPT_HttpHeaders::Parse(215)/676: FINEST: header USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
19304.125: PLT_HttpServer::ProcessHttpRequest(142)/676: FINE: Received POST Request from 192.168.1.28 for /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml
19304.125: PLT_HttpHelper::ToLog(410)/676: FINER: 
POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
HOST: 192.168.1.20:56923
CONTENT-LENGTH: 645
CONTENT-TYPE: text/xml; charset="utf-8"
SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0

19304.125: FbMediaServer::ProcessHttpRequest(306)/676: INFO: received LAN request: [url=http://192.168.1.20:56923/ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml]http://192.168.1.20:56923/ContentDirectory...b7d/control.xml[/url] (User-Agent: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0)
19304.125: PLT_DeviceHost::ProcessHttpRequest(376)/676: FINER: PLT_DeviceHost Received Request:
19304.125: PLT_HttpHelper::ToLog(410)/676: FINER: 
POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
HOST: 192.168.1.20:56923
CONTENT-LENGTH: 645
CONTENT-TYPE: text/xml; charset="utf-8"
SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0

19304.125: PLT_DeviceHost::ProcessHttpPostRequest(555)/676: FINE: Received Browse action from 192.168.1.28
19304.125: FbMediaServer::OnAction(243)/676: INFO: received action 'Browse', args = { ObjectID=0, BrowseFlag=BrowseDirectChildren, Filter=@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resol
ution,res@protocolInfo,res@nrAudioChannels,
res@sampleFrequency, StartingIndex=0, RequestedCount=20, SortCriteria= }
19304.125: PLT_MediaServer::OnBrowse(380)/676: INFO: Received BrowseDirectChildren from 192.168.1.28 for id = 0 with filter = @id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resol
ution,res@protocolInfo,res@nrAudioChannels,
res@sampleFrequency, start = 0, count = 20
19304.140: FbMediaServer::OnAction(260)/676: INFO: action 'Browse' response: args = { ObjectID=0, BrowseFlag=BrowseDirectChildren, Filter=@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resol
ution,res@protocolInfo,res@nrAudioChannels,
res@sampleFrequency, StartingIndex=0, RequestedCount=20, SortCriteria=, NumberReturned=3, TotalMatches=3, UpdateID=0 }
19304.140: PLT_HttpServerSocketTask::Write(322)/676: FINER: PLT_HttpServerTask Sending response:
19304.140: PLT_HttpHelper::ToLog(496)/676: FINER: 
HTTP/1.1 200 OK
Server: Platinum/0.5.4.0
Content-Type: text/xml; charset="utf-8"
Ext: 
Connection: keep-alive
Content-Length: 1407

19304.140: NPT_HttpHeader::Emit(118)/676: FINEST: header Server: Platinum/0.5.4.0
19304.140: NPT_HttpHeader::Emit(118)/676: FINEST: header Content-Type: text/xml; charset="utf-8"
19304.140: NPT_HttpHeader::Emit(118)/676: FINEST: header Ext: 
19304.140: NPT_HttpHeader::Emit(118)/676: FINEST: header Connection: keep-alive
19304.140: NPT_HttpHeader::Emit(118)/676: FINEST: header Content-Length: 1407
19304.140: PLT_HttpServerSocketTask::Write(336)/676: FINER: keep_alive = true, headers_only = false
19304.140: PLT_HttpServerSocketTask::Write(341)/676: FINER: NPT_StreamToStreamCopy begin
19304.140: PLT_HttpServerSocketTask::Write(349)/676: FINER: NPT_StreamToStreamCopy end
19304.156: NPT_HttpRequest::Parse(616)/676: FINE: NPT_CHECK failed, result=-20302 (NPT_ERROR_EOS) [(stream.ReadLine(line, NPT_HTTP_PROTOCOL_MAX_LINE_LENGTH))]
19304.156: NPT_Win32Thread::Start(483)/3348: FINER: creating thread
19304.156: PLT_HttpListenTask<class PLT_HttpServerListener>::DoRun(156)/3348: FINE: current http task count = 2
19304.156: NPT_Win32Thread::EntryPoint(450)/3392: FINER: thread in =======================
19304.156: NPT_HttpRequest::Parse(617)/3392: FINEST: http request: POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
19304.156: NPT_HttpHeaders::Parse(185)/3392: FINEST: header - HOST: 192.168.1.20:56923
19304.156: NPT_HttpHeaders::Parse(185)/3392: FINEST: header - CONTENT-LENGTH: 435
19304.156: NPT_HttpHeaders::Parse(185)/3392: FINEST: header - CONTENT-TYPE: text/xml; charset="utf-8"
19304.156: NPT_HttpHeaders::Parse(185)/3392: FINEST: header - SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
19304.156: NPT_HttpHeaders::Parse(215)/3392: FINEST: header USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0
19304.156: PLT_HttpServer::ProcessHttpRequest(142)/3392: FINE: Received POST Request from 192.168.1.28 for /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml
19304.156: PLT_HttpHelper::ToLog(410)/3392: FINER: 
POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
HOST: 192.168.1.20:56923
CONTENT-LENGTH: 435
CONTENT-TYPE: text/xml; charset="utf-8"
SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0

19304.156: FbMediaServer::ProcessHttpRequest(306)/3392: INFO: received LAN request: [url=http://192.168.1.20:56923/ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml]http://192.168.1.20:56923/ContentDirectory...b7d/control.xml[/url] (User-Agent: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0)
19304.156: PLT_DeviceHost::ProcessHttpRequest(376)/3392: FINER: PLT_DeviceHost Received Request:
19304.156: PLT_HttpHelper::ToLog(410)/3392: FINER: 
POST /ContentDirectory/42adc705-71cb-b393-8af2-391829a1bb7d/control.xml HTTP/1.1
HOST: 192.168.1.20:56923
CONTENT-LENGTH: 435
CONTENT-TYPE: text/xml; charset="utf-8"
SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
USER-AGENT: Linux/2.6.31-1.0 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.5.0

19304.156: PLT_DeviceHost::ProcessHttpPostRequest(555)/3392: FINE: Received Browse action from 192.168.1.28
19304.156: FbMediaServer::OnAction(243)/3392: INFO: received action 'Browse', args = { ObjectID=0, BrowseFlag=BrowseDirectChildren, Filter=upnp:class, StartingIndex=0, RequestedCount=100, SortCriteria= }
19304.156: PLT_MediaServer::OnBrowse(361)/3392: WARNING: Invalid arguments (0, 100, upnp:class)
19304.156: FbMediaServer::OnAction(260)/3392: INFO: action 'Browse' response: args = { ObjectID=0, BrowseFlag=BrowseDirectChildren, Filter=upnp:class, StartingIndex=0, RequestedCount=100, SortCriteria= }
19304.156: PLT_HttpServerSocketTask::Write(322)/3392: FINER: PLT_HttpServerTask Sending response:
19304.156: PLT_HttpHelper::ToLog(496)/3392: FINER: 
HTTP/1.0 500 Internal Server Error
Server: Platinum/0.5.4.0
Content-Type: text/xml; charset="utf-8"
Ext: 
Connection: keep-alive
Content-Length: 435

foo_upnp

Reply #1117 –
Quote from: klui on
I'm trying to stream to my LG TV but it is having trouble connecting to Foobar:uPnP and display a list of files. Would the log below help to isolate what the problem is? It looks like it's having trouble with a upnp:class call.


New version below should fix this issue.


A new version finally. No major new feature but some bugfixes and refinements here and there (see first post to download or use autoupdate in foobar2000).

Code: [Select]
v 0.99.31

- server: fix: rare crashes
- server: chg: always append the Windows computer name to the Media Server and Renderer name
- server: chg: added support for 24 bits LPCM (L24) for streaming regular files and Playback Stream Capture (note that foobar2000 won't play L24). Still largely untested so there might be issues.
- server: chg: handle PlugPlayer search query (returns albums whose title contain search string)
- server: fix: in tracks from VA albums, only Album Artist was specified (no track artist)
- server: fix: some Browse requests could fail due to exotic unhandled filters requested (some Samsung TVs and LG TVs)
- server: fix: tracks in Folders node are now sorted using %path_sort% (ie Explorer order) instead of a more sophitiscated order that messed VA albums track order in some cases.
- server: fix: LinnDS not displaying artist name on the unit's LCD screen
- server: fixed crash if playing a track whose full filename with path contained character %
- server: fix: for track Album Artist metadata use $meta(album artist) if present,  instead of field remapping %album artist%
- server: in foo_upnp_ml_tree.xml, if a <SubTree> definition is bogus (bad query syntax for example), skip the definition instead of reverting to the default tree
- server: tweaks for BubbleDS: support query to get random tracks, search for albums, added dc:creator in album container

- server: query defintion in <SubTree> supports SORT and operators involving system time: DURING, SINCE, ...
if SORT is specified, eventual <spec> subnodes are ignored (query 1). The result is that this node will contain tracks
if SORT is not specified but the query still has a sort order implied (query1, implied sort order ASCENDING), <spec> tags are taken into account to further split the results

<SubTree label="Last 4 weeks tracks descending" query="%last_modified% DURING LAST 4 WEEKS SORT DESCENDING BY %last_modified%"/>
<SubTree label="Last 4 weeks albums" query="%last_modified% DURING LAST 4 WEEKS">
    <spec type="object.container.album.musicAlbum">%album%[ '['%album artist%']']</spec>
</SubTree>

- controller: removed experimental "Play Stream Capture Multi" as it is unusuable
- controller: When switching renderers, do not automatically clear the UPnP Controller Playback Queue playlist (except when switching to Linn DS renderer)

foo_upnp

Reply #1118 –
still no luck on samsung ue40c7700...  volume-control and applying changes to streaming profiles-settings (connection gets reset) seems to work, but its not starting to play anything (of course i have tracks added to UPnP Controller Playback Queue). anything else i could do wrong? is it helpful to post log again or can you already tell that it won't work with this samsung series? thx anyways for adding this feature to foobar! not giving up on hoping for a working connection between samsung some day...

foo_upnp

Reply #1119 –
Hi bubbleguuum, thanks for a making a great foobar2000 component.  I would like to request a way to 'Refresh' the list of songs on a remote playlist.  If songs are added to a remote playlist that is already being viewed on the local foobar2000 instance, you have to restart the program to get updated list results.

Please let me know if I'm being unclear.

foo_upnp

Reply #1120 –
Hi, any idea what Stop Error: Action failed (501) means and how to fix it?

I'm getting it quite regularly and the whole thing freezes. Even if i close FB2k and reopen it, the error message remains and nothing works until i reboot.

trying to play to a LaCie MiniHD...

foo_upnp

Reply #1121 –
Wow! Just discovered this, it works fantastically with my iphone!
I still don't undersdant one thing though: if i would want to access my media server from another device out of my home network, what would i need?

foo_upnp

Reply #1122 –
I witness a problem very similar to i b listening is experiencing (http://www.hydrogenaudio.org/forums/index.php?showtopic=69664&view=findpost&p=742921). I'm not sure whether it is foo_upnp or WDTVLive specific though.

0.99.31, default settings. when I'm trying to use "Play on..." UPNP Controller feature, playback successfully starts on remote device, and stops after a few seconds. It takes a several tries on the same song, including changes to streaming profile 'always transcode to mp3' and back to default 'never', to make "Play on..." work without interruption.

here are logs of interrupted "Play on..." sessions: default settings and mp3 transcode always.

foo_upnp

Reply #1123 –
Quote from: oblikoamorale on
I witness a problem very similar to i b listening is experiencing (http://www.hydrogenaudio.org/forums/index.php?showtopic=69664&view=findpost&p=742921). I'm not sure whether it is foo_upnp or WDTVLive specific though.

0.99.31, default settings. when I'm trying to use "Play on..." UPNP Controller feature, playback successfully starts on remote device, and stops after a few seconds. It takes a several tries on the same song, including changes to streaming profile 'always transcode to mp3' and back to default 'never', to make "Play on..." work without interruption.

here are logs of interrupted "Play on..." sessions: default settings and mp3 transcode always.



This is indeed the same problem than reported by i b listening. At some point the WD Live makes a HTTP request notifying of some state change to foo_upnp. However theres a problem with this notification (it is unclear on which side the problem is at this point) and foo_upnp HTTP server responds with code 412.
After that, a subsequent HTTP request from foo_upnp to the WD Live fails with a connection refused, as a vengeance.

I'll see if I can sort out where this invalid notification comes from.

Did that problem existed with 0.99.29 (I exepect that it did since nothing changed in this area).

foo_upnp

Reply #1124 –
Quote from: Bryter on
Wow! Just discovered this, it works fantastically with my iphone!
I still don't undersdant one thing though: if i would want to access my media server from another device out of my home network, what would i need?


It depends on what device you'll be using. Can you be more specific ?