@mpuckett: not possible without rewriting foo_upnp, foobar2000 media library handling, and facets

Quote from: angstrom on 2011-02-26 14:53:48

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...

It means  that this device didn't like being stopped by the Controller. Record a log so I can have a look (first post for instruction on how to log).

foo_upnp: 1049.859: upnp_server_conf::get_profile(720)/2608: INFO: using profile 'HTC G1', matching header (User-Agent:CORE/6.506.4.1 OpenCORE/2.02 (Linux;Android 1.6))
foo_upnp: opened 'file://\\media\Audio\Music\flac\Images\Sheryl Crow\[1993] Tuesday Night Music Club\Sheryl Crow - [1993] Tuesday Night Music Club.flac' for streaming, profile: Default, WAV / 44100 Hz / 16 bits / 2 channels, replaygain: on, DSP chain: off
foo_upnp: 1049.969: PLT_HttpServerSocketTask::Write(348)/2608: WARNING: NPT_CHECK failed, result=-20401 (NPT_ERROR_CONNECTION_ABORTED) [(NPT_StreamToStreamCopy( *body_stream.AsPointer(), *output_stream.AsPointer(), 0, entity->GetContentLength(), 0, abort))]

bubblegumm,

I am having a problem with streaming profiles that is driving me crazy. I am trying to stream to my htc G1 android phone which is running the upnplay application. My library is flac images so I have created a new streaming profile named HTC G1 which is set up to always transcode to mp3. I can see that foo_upnp recognizes and selects the HTC G1 profile but then decides to use the default profile to stream the content as a wav which fails. If I change the default profile to always transcode to mp3 it works fine. Here are the relevant lines from the console log which demonstrate the failure or I'll be happy to recapture the whole file and send it to you if you prefer. I know I must be doing something stupid with the profile but I think I've tried everything and no luck.

Code: [Select]

foo_upnp: 1049.859: upnp_server_conf::get_profile(720)/2608: INFO: using profile 'HTC G1', matching header (User-Agent:CORE/6.506.4.1 OpenCORE/2.02 (Linux;Android 1.6))
foo_upnp: opened 'file://\\media\Audio\Music\flac\Images\Sheryl Crow\[1993] Tuesday Night Music Club\Sheryl Crow - [1993] Tuesday Night Music Club.flac' for streaming, profile: Default, WAV / 44100 Hz / 16 bits / 2 channels, replaygain: on, DSP chain: off
foo_upnp: 1049.969: PLT_HttpServerSocketTask::Write(348)/2608: WARNING: NPT_CHECK failed, result=-20401 (NPT_ERROR_CONNECTION_ABORTED) [(NPT_StreamToStreamCopy( *body_stream.AsPointer(), *output_stream.AsPointer(), 0, entity->GetContentLength(), 0, abort))]

Any pointers to what I am doing wrong would be greatly appreciated.

Hi.

Using foo_upnp with my WDTVLive.  It works quickly and streams fine but only for about 10 minutes.  Then the WDTVLive claims the server is unavailable.  I have looked in the log but not being that smart it doesn't make a lot of sense to me.  My Soundbridge streams from it with no problems at all.  Any suggestions as to what might need attention?

Thanks

10196.625: PLT_HttpHelper::ToLog(441)/3456: INFO: 
GET /content/83bc4e32bc62d0f36669f43a7a8b4607.mp3 HTTP/1.0
Host: 192.168.2.8:56923
Content-Length: 0
transferMode.dlna.org: Streaming
User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks

10196.625: upnp_server_conf::get_profile(726)/3456: INFO: using default profile (User-Agent:INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks)
opened 'file://H:\My Music\David Crowder Band\A Collision Or (3 + 4 = 7)\03 Here is our King.mp3' for streaming
10196.938: FbMediaServer::ProcessHttpRequest(330)/2340: INFO: received LAN request: http://192.168.2.8:56923/albumart/83bc4e32bc62d0f36669f43a7a8b4607 (User-Agent: ALPHA Networks/CTID)
10196.938: PLT_HttpHelper::ToLog(441)/2340: INFO: 
GET /albumart/83bc4e32bc62d0f36669f43a7a8b4607 HTTP/1.0
HOST: 192.168.2.8:56923
User-Agent: ALPHA Networks/CTID
Accept: */*

10275.172: FbMediaServer::ProcessHttpRequest(330)/2660: INFO: received LAN request: http://192.168.2.3:56923/DeviceDescription.xml (User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 Intel MicroStack/1.0.1423)
10275.172: FbMediaServer::ProcessGetDescription(414)/2660: INFO: ProcessGetDescription: user-agent: INTEL_NMPR/2.1 DLNADOC/1.50 Intel MicroStack/1.0.1423
10275.407: FbMediaServer::ProcessHttpRequest(330)/2656: INFO: received LAN request: http://192.168.2.8:56923/DeviceDescription.xml (User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 Intel MicroStack/1.0.1423)
10275.407: FbMediaServer::ProcessGetDescription(414)/2656: INFO: ProcessGetDescription: user-agent: INTEL_NMPR/2.1 DLNADOC/1.50 Intel MicroStack/1.0.1423
10275.875: PLT_HttpServerSocketTask::Write(348)/3456: WARNING: NPT_CHECK failed, result=-20401 (NPT_ERROR_CONNECTION_ABORTED) [(NPT_StreamToStreamCopy( *body_stream.AsPointer(), *output_stream.AsPointer(), 0, entity->GetContentLength(), 0, abort))]
10360.219: PLT_UPnP::Stop(312)/540: INFO: Stopping UPnP...
10360.235: PLT_HttpServerSocketTask::Read(216)/1144: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [(res)]
10360.235: PLT_HttpServerSocketTask::Read(216)/1156: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [(res)]
10360.235: PLT_HttpServerSocketTask::Read(216)/3964: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [(res)]
10360.235: PLT_HttpServerSocketTask::Read(216)/3324: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [(res)]
10361.047: PLT_HttpServerSocketTask::Read(216)/4032: WARNING: NPT_CHECK failed, result=-1 (FAILURE) [(res)]

Quote from: blammo on 2011-08-19 15:57:15

Hi.

Using foo_upnp with my WDTVLive.  It works quickly and streams fine but only for about 10 minutes.  Then the WDTVLive claims the server is unavailable.  I have looked in the log but not being that smart it doesn't make a lot of sense to me.  My Soundbridge streams from it with no problems at all.  Any suggestions as to what might need attention?

Thanks

No idea but from experience WDTVLive exhibit various level of buggyness depending on models.  You can record a log as explained in the first post if you wish.

There are 2 different User-Agent used by UPnPlay, one for browse requests and one for the streaming requests. The "using profile 'HTC G1'" line is wrong and misleading in the case the browsing User-Agent is different from the streaming one.  The important one is the browsing User-Agent. UPnPlay doesn't set any User-Agent for browse requests, so it cannot be identified and you're stuck using the default profile.

I suggest that you use my app BubbleUPnP instead, it supports FLAC on all phones. You can use "BubbleUPnP" as a substring for User-Agent to identify it.

does anyone else have the problem if foobar is running with foo_upnp.dll, that the logfile the component writes (foo_upnp.log) isn't accessible by any other program e.g. baretail or any other Logfile-tail-thingy?
Is this behaviour intended?

User-Agent is set by the UPnP app on the phone then? Who sets the browsing User-Agent? Are they both set by the UPnP app?

Quote from: mpuckett on 2011-08-26 05:00:36

User-Agent is set by the UPnP app on the phone then? Who sets the browsing User-Agent? Are they both set by the UPnP app?

yes. the streaming User-Agent is often set by the core (system) HTTP streamer of the phone, used by the app.

foo_upnp: 497.859: FbMediaServer::ProcessHttpRequest(330)/824: INFO: received LAN request: [url=http://192.168.0.206/content/f143900b816415cb5c0e2fb9ded7d5bb.mp3?profile_id=0&convert=mp3&subsong=8]http://192.168.0.206/content/f143900b81641...3&subsong=8[/url] (User-Agent: CORE/6.506.4.1 OpenCORE/2.02 (Linux;Android 1.6))
foo_upnp: 497.859: PLT_HttpHelper::ToLog(441)/824: INFO: 
GET /content/f143900b816415cb5c0e2fb9ded7d5bb.mp3?profile_id=0&convert=mp3&subsong=8 HTTP/1.0
Host: 192.168.0.206
User-Agent: CORE/6.506.4.1 OpenCORE/2.02 (Linux;Android 1.6)
Connection: Close
foo_upnp: 497.890: upnp_server_conf::get_profile(720)/824: INFO: using profile 'HTC G1', matching header (User-Agent:CORE/6.506.4.1 OpenCORE/2.02 (Linux;Android 1.6))
foo_upnp: opened 'file://\\sarah\media\Audio\Music\flac\Images\Shawn Colvin\[2009] Live\Shawn Colvin - [2009] Live.flac' for streaming, profile: Default, MP3 / CBR 320 kbps, replaygain: on, DSP chain: off

Bubbleguum,
I remain puzzled by this behavior. In looking at more of the debug output it seems as if the player is making a transcode request using the correct profile, does a GET on the transcoded file and then again requests the flac file using the default profile?

Code: [Select]

foo_upnp: 497.859: FbMediaServer::ProcessHttpRequest(330)/824: INFO: received LAN request: [url=http://192.168.0.206/content/f143900b816415cb5c0e2fb9ded7d5bb.mp3?profile_id=0&convert=mp3&subsong=8]http://192.168.0.206/content/f143900b81641...3&subsong=8[/url] (User-Agent: CORE/6.506.4.1 OpenCORE/2.02 (Linux;Android 1.6))
foo_upnp: 497.859: PLT_HttpHelper::ToLog(441)/824: INFO: 
GET /content/f143900b816415cb5c0e2fb9ded7d5bb.mp3?profile_id=0&convert=mp3&subsong=8 HTTP/1.0
Host: 192.168.0.206
User-Agent: CORE/6.506.4.1 OpenCORE/2.02 (Linux;Android 1.6)
Connection: Close
foo_upnp: 497.890: upnp_server_conf::get_profile(720)/824: INFO: using profile 'HTC G1', matching header (User-Agent:CORE/6.506.4.1 OpenCORE/2.02 (Linux;Android 1.6))
foo_upnp: opened 'file://\\sarah\media\Audio\Music\flac\Images\Shawn Colvin\[2009] Live\Shawn Colvin - [2009] Live.flac' for streaming, profile: Default, MP3 / CBR 320 kbps, replaygain: on, DSP chain: off

foo_upnp: 92842.909: PLT_MediaServer::OnBrowse(380)/4972: INFO: Received BrowseDirectChildren from 192.168.1.5 for id = 0/1/1 with filter = *, start = 66, count = 50
foo_upnp: 92842.910: upnp_server_conf::get_profile(738)/4972: INFO: using profile 'BubbleUPnP', matching header (User-Agent:BubbleUPnP UPnP/1.1)

In your case (UPnPPlay browsing) you will see that there is no User-Agent and thus the default profile is used (even if the log snippet above says wronly otherwise as explained above ut without consequence).
The thing is that you definitely cannot identify UPnPPlay because it doesn't set a User-Agent for *browse* requests.