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