Sony BDP-S3100 can't use UMS for Internet Radio
Posted: Sat Mar 15, 2014 3:24 pm
Hi,
I have been previously using PMS successfully for over a year to stream internet radio to my BDP-S3100 (also to my SA-NS310) until the latest firmware update (M14.R.0172).
After latest firmware, I can still play local music and movies, but internet radio is stuffed. This is sad, because that was 99% of what I was using PMS for. Looking for updates, it seems that your project has taken over.
Please note that I can STILL play internet radio using PMS 1.72 and the (very) old mplayer transcoder. So in my mind we have a transcoding incompatibility since the new firmware, which prevents sony devices running their latest firmware to play internet radio from UMS/PMS using any transcoder except mplayer (UMS doesnt use mplayer). i.e. the issue is exactly the same with my SA-NS310 since that devices latest firware update - I can send it mp3 etc but not internet radio of any sort.
Here is a debug log (subnets masked):
I have been previously using PMS successfully for over a year to stream internet radio to my BDP-S3100 (also to my SA-NS310) until the latest firmware update (M14.R.0172).
After latest firmware, I can still play local music and movies, but internet radio is stuffed. This is sad, because that was 99% of what I was using PMS for. Looking for updates, it seems that your project has taken over.
Please note that I can STILL play internet radio using PMS 1.72 and the (very) old mplayer transcoder. So in my mind we have a transcoding incompatibility since the new firmware, which prevents sony devices running their latest firmware to play internet radio from UMS/PMS using any transcoder except mplayer (UMS doesnt use mplayer). i.e. the issue is exactly the same with my SA-NS310 since that devices latest firware update - I can send it mp3 etc but not internet radio of any sort.
Here is a debug log (subnets masked):
Code: Select all
TRACE 2014-03-15 14:06:28.152 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:28.153 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49938,localport=5001]
TRACE 2014-03-15 14:06:28.153 [Request Handler] Received on socket: POST /upnp/control/content_directory HTTP/1.1
TRACE 2014-03-15 14:06:28.153 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";
TRACE 2014-03-15 14:06:28.153 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:28.153 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";
TRACE 2014-03-15 14:06:28.153 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:28.153 [Request Handler] Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
TRACE 2014-03-15 14:06:28.153 [Request Handler] Received on socket: Content-Length: 597
TRACE 2014-03-15 14:06:28.153 [Request Handler] Received on socket: Content-Type: text/xml; charset="utf-8"
TRACE 2014-03-15 14:06:28.153 [Request Handler] Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
TRACE 2014-03-15 14:06:28.153 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:28.154 [Request Handler] HTTP: upnp/control/content_directory / 0-0
TRACE 2014-03-15 14:06:28.154 [Request Handler] Wrote on socket: HTTP/1.1 200 OK
TRACE 2014-03-15 14:06:28.155 [Request Handler] Wrote on socket: CONTENT-TYPE: text/xml; charset="utf-8"
TRACE 2014-03-15 14:06:28.155 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:28.158 [Request Handler] End of analysis for SWR4 Rheinland-Pfalz
TRACE 2014-03-15 14:06:28.158 [Request Handler] Network debugger: Rundfunk SWR4 http-get:*:audio/wav:DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01 size=9223372034707292159 http://xxx.xxx.xxx.6:5001/get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:28.158 [Request Handler] Network debugger: http-get:*:audio/wav:DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01 size=9223372034707292159 http://xxx.xxx.xxx.6:5001/get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:28.158 [Request Handler] Network debugger: http-get:*:audio/wav:DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01 size=9223372034707292159 http://xxx.xxx.xxx.6:5001/get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:28.158 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:28.158 [Request Handler] Wrote on socket: Content-Length: 1994
TRACE 2014-03-15 14:06:28.158 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:28.158 [Request Handler] Close connection
TRACE 2014-03-15 14:06:28.426 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
TRACE 2014-03-15 14:06:29.811 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
TRACE 2014-03-15 14:06:30.111 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
TRACE 2014-03-15 14:06:30.455 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
TRACE 2014-03-15 14:06:30.458 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
TRACE 2014-03-15 14:06:31.955 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
TRACE 2014-03-15 14:06:32.491 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
TRACE 2014-03-15 14:06:32.492 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.492 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.492 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.493 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.493 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.493 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.494 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.494 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.494 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.495 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.495 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.495 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.496 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.496 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.10:1900]
TRACE 2014-03-15 14:06:32.803 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
TRACE 2014-03-15 14:06:32.805 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:32.831 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:32.831 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49954,localport=5001]
TRACE 2014-03-15 14:06:32.831 [Request Handler] Received on socket: HEAD /get/0$8$2/Rundfunk+SWR4 HTTP/1.1
TRACE 2014-03-15 14:06:32.832 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:32.832 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:32.832 [Request Handler] Received on socket: Accept: */*
TRACE 2014-03-15 14:06:32.832 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player"
TRACE 2014-03-15 14:06:32.832 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"
TRACE 2014-03-15 14:06:32.832 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2014-03-15 14:06:32.832 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:32.832 [Request Handler] HTTP: get/0$8$2/Rundfunk+SWR4 / 0-0
TRACE 2014-03-15 14:06:32.832 [Request Handler] Wrote on socket: HTTP/1.1 200 OK
TRACE 2014-03-15 14:06:32.832 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:32.832 [Request Handler] discover 0$8 refresh forced: false
TRACE 2014-03-15 14:06:32.832 [Request Handler] Asked stream chunk : TimeRange [start=0.0, end=0.0] of Rundfunk SWR4 and player VLC Web Audio (Legacy)
DEBUG 2014-03-15 14:06:32.832 [Request Handler] Starting transcode/remux of Rundfunk SWR4 with media info: container: null, bitrate: 0, size: 0, video codec: null, duration: null, width: 0, height: 0, frame rate: null, muxing mode: null, mime type: null
DEBUG 2014-03-15 14:06:32.832 [mkfifo-73] Starting mkfifo --mode=777 /tmp/universalmediaserver/VLC1394852792832.wav
DEBUG 2014-03-15 14:06:32.834 [mkfifo-73] Unix process ID (mkfifo): 27580
TRACE 2014-03-15 14:06:32.841 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:32.862 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:32.883 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:32.903 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:32.924 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:32.958 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:32.982 [Request Handler] Sleeping for 6000 milliseconds
DEBUG 2014-03-15 14:06:32.982 [vlc-74] Starting vlc -I dummy --sout #transcode{acodec=s16l,channels=2}:standard{access=file,mux=wav,dst="/tmp/universalmediaserver/VLC1394852792832.wav"} http://swr.ic.llnwd.net/stream/swr_mp3_s_swr4rpa vlc://quit
DEBUG 2014-03-15 14:06:32.988 [vlc-74] Reading pipe: /tmp/universalmediaserver/VLC1394852792832.wav
TRACE 2014-03-15 14:06:32.989 [vlc-74] Opening file /tmp/universalmediaserver/VLC1394852792832.wav for reading...
TRACE 2014-03-15 14:06:33.002 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:33.030 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:33.051 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:33.072 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
TRACE 2014-03-15 14:06:33.093 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.16:50201]
DEBUG 2014-03-15 14:06:33.132 [vlc-74-2] [0x957fd58] dummy interface: using the dummy interface module...
TRACE 2014-03-15 14:06:33.185 [vlc-74] Successfully initialized buffer to 52,428,800 bytes.
DEBUG 2014-03-15 14:06:33.185 [vlc-74] Attaching thread: vlc-74
DEBUG 2014-03-15 14:06:33.193 [vlc-74] Unix process ID (vlc): 27585
TRACE 2014-03-15 14:06:33.196 [vlc-74-Timer] buffered: 0 bytes / inputs: 0
TRACE 2014-03-15 14:06:33.907 [UPNPHelper] Receiving a NOTIFY from [xxx.xxx.xxx.18:5645]
DEBUG 2014-03-15 14:06:35.028 [vlc-74-2] [0x95f97b0] access_http access: Raw-audio server found, mp3 demuxer selected
TRACE 2014-03-15 14:06:35.190 [vlc-74-Timer] buffered: 0 bytes / inputs: 0
TRACE 2014-03-15 14:06:37.190 [vlc-74-Timer] buffered: 584,108 bytes / inputs: 0
TRACE 2014-03-15 14:06:38.982 [Request Handler] Finished sleeping for 6000 milliseconds
TRACE 2014-03-15 14:06:38.983 [Request Handler] Wrote on socket: Content-Type: audio/wav
TRACE 2014-03-15 14:06:38.983 [Request Handler] Wrote on socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2014-03-15 14:06:38.983 [Request Handler] Wrote on socket: Connection: keep-alive
TRACE 2014-03-15 14:06:38.983 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:38.983 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:38.983 [Request Handler] Sending stream: 0 bytes of get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:38.983 [Request Handler] Ready to Stop: true
INFO 2014-03-15 14:06:38.984 [StartPlaying Event] Started playing Rundfunk SWR4 on your Sony Blu-ray
DEBUG 2014-03-15 14:06:38.984 [StartPlaying Event] The full filename of which is: http://swr.ic.llnwd.net/stream/swr_mp3_s_swr4rpa and the address of the renderer is: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:38.984 [Request Handler] Close connection
TRACE 2014-03-15 14:06:38.990 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:38.991 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49955,localport=5001]
TRACE 2014-03-15 14:06:38.991 [Request Handler] Received on socket: GET /get/0$8$2/Rundfunk+SWR4 HTTP/1.1
TRACE 2014-03-15 14:06:38.991 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:38.991 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:38.991 [Request Handler] Received on socket: Accept: */*
TRACE 2014-03-15 14:06:38.991 [Request Handler] Received on socket: Range: bytes=0-
TRACE 2014-03-15 14:06:38.991 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player"
TRACE 2014-03-15 14:06:38.991 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"
TRACE 2014-03-15 14:06:38.992 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2014-03-15 14:06:38.992 [Request Handler] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-15 14:06:38.992 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:38.992 [Request Handler] HTTP: get/0$8$2/Rundfunk+SWR4 / 0--1
TRACE 2014-03-15 14:06:38.992 [Request Handler] Wrote on socket: HTTP/1.1 206 Partial Content
TRACE 2014-03-15 14:06:38.992 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:38.992 [Request Handler] discover 0$8 refresh forced: false
TRACE 2014-03-15 14:06:38.992 [Request Handler] Wrote on socket: TransferMode.DLNA.ORG: Streaming
TRACE 2014-03-15 14:06:38.992 [Request Handler] Asked stream chunk : TimeRange [start=0.0, end=0.0] of Rundfunk SWR4 and player VLC Web Audio (Legacy)
TRACE 2014-03-15 14:06:38.992 [Request Handler] Ready to Stop: false
TRACE 2014-03-15 14:06:38.992 [Request Handler] Wrote on socket: Content-Type: audio/wav
TRACE 2014-03-15 14:06:38.996 [Request Handler] Using chunked response. Sending 652076 bytes.
TRACE 2014-03-15 14:06:38.996 [Request Handler] Wrote on socket: Content-Range: bytes 0-652075/*
TRACE 2014-03-15 14:06:38.996 [Request Handler] Wrote on socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2014-03-15 14:06:38.996 [Request Handler] Wrote on socket: Connection: keep-alive
TRACE 2014-03-15 14:06:38.996 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:38.996 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:38.996 [Request Handler] Suspend Read: readCount=0 / writeCount=652076
TRACE 2014-03-15 14:06:39.190 [vlc-74-Timer] buffered: 667,052 bytes / inputs: 1
TRACE 2014-03-15 14:06:41.190 [vlc-74-Timer] buffered: 756,908 bytes / inputs: 1
TRACE 2014-03-15 14:06:43.190 [vlc-74-Timer] buffered: 832,940 bytes / inputs: 1
TRACE 2014-03-15 14:06:45.190 [vlc-74-Timer] buffered: 937,772 bytes / inputs: 1
TRACE 2014-03-15 14:06:46.498 [Request Handler] Resume Read: readCount=0 / writeCount=990764
TRACE 2014-03-15 14:06:46.506 [Request Handler] Sending stream with premature end: 0 bytes of get/0$8$2/Rundfunk+SWR4. Reason: Connection reset by peer
TRACE 2014-03-15 14:06:46.506 [Request Handler] Ready to Stop: true
TRACE 2014-03-15 14:06:46.506 [Request Handler] Sending stream: 0 bytes of get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:46.513 [Request Handler] Close connection
TRACE 2014-03-15 14:06:46.521 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:46.525 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49956,localport=5001]
TRACE 2014-03-15 14:06:46.525 [Request Handler] Received on socket: GET /get/0$8$2/Rundfunk+SWR4 HTTP/1.1
TRACE 2014-03-15 14:06:46.525 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:46.525 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:46.525 [Request Handler] Received on socket: Accept: */*
TRACE 2014-03-15 14:06:46.525 [Request Handler] Received on socket: Range: bytes=0-511
TRACE 2014-03-15 14:06:46.525 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player"
TRACE 2014-03-15 14:06:46.525 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"
TRACE 2014-03-15 14:06:46.526 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2014-03-15 14:06:46.526 [Request Handler] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-15 14:06:46.526 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:46.526 [Request Handler] HTTP: get/0$8$2/Rundfunk+SWR4 / 0-511
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket: HTTP/1.1 206 Partial Content
TRACE 2014-03-15 14:06:46.526 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:46.526 [Request Handler] discover 0$8 refresh forced: false
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket: TransferMode.DLNA.ORG: Streaming
TRACE 2014-03-15 14:06:46.526 [Request Handler] Asked stream chunk : ByteRange [start=0, end=511] of Rundfunk SWR4 and player VLC Web Audio (Legacy)
TRACE 2014-03-15 14:06:46.526 [Request Handler] Ready to Stop: false
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket: Content-Type: audio/wav
TRACE 2014-03-15 14:06:46.526 [Request Handler] Using chunked response. Sending 512 bytes.
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket: Content-Range: bytes 0-511/*
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket: Connection: keep-alive
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket: Content-Length: 512
TRACE 2014-03-15 14:06:46.526 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:46.526 [Request Handler] Suspend Read: readCount=0 / writeCount=990764
TRACE 2014-03-15 14:06:47.190 [vlc-74-Timer] buffered: 1,012,652 bytes / inputs: 1
TRACE 2014-03-15 14:06:48.026 [Request Handler] Resume Read: readCount=0 / writeCount=1058732
TRACE 2014-03-15 14:06:48.034 [Request Handler] Sending stream with premature end: 0 bytes of get/0$8$2/Rundfunk+SWR4. Reason: Connection reset by peer
TRACE 2014-03-15 14:06:48.034 [Request Handler] Ready to Stop: true
TRACE 2014-03-15 14:06:48.034 [Request Handler] Sending stream: 0 bytes of get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:48.040 [Request Handler] Close connection
TRACE 2014-03-15 14:06:48.064 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.065 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49957,localport=5001]
TRACE 2014-03-15 14:06:48.065 [Request Handler] Received on socket: HEAD /get/0$8$2/Rundfunk+SWR4 HTTP/1.1
TRACE 2014-03-15 14:06:48.065 [Request Handler] Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
TRACE 2014-03-15 14:06:48.065 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.065 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:48.065 [Request Handler] Received on socket: Accept: */*
TRACE 2014-03-15 14:06:48.065 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player"
TRACE 2014-03-15 14:06:48.065 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"
TRACE 2014-03-15 14:06:48.065 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2014-03-15 14:06:48.065 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:48.065 [Request Handler] HTTP: get/0$8$2/Rundfunk+SWR4 / 0-0
TRACE 2014-03-15 14:06:48.065 [Request Handler] Wrote on socket: HTTP/1.1 200 OK
TRACE 2014-03-15 14:06:48.065 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:48.065 [Request Handler] discover 0$8 refresh forced: false
TRACE 2014-03-15 14:06:48.066 [Request Handler] Asked stream chunk : TimeRange [start=0.0, end=0.0] of Rundfunk SWR4 and player VLC Web Audio (Legacy)
TRACE 2014-03-15 14:06:48.066 [Request Handler] Ready to Stop: false
TRACE 2014-03-15 14:06:48.066 [Request Handler] Wrote on socket: Content-Type: audio/wav
TRACE 2014-03-15 14:06:48.073 [Request Handler] Wrote on socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2014-03-15 14:06:48.073 [Request Handler] Wrote on socket: Connection: keep-alive
TRACE 2014-03-15 14:06:48.073 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:48.073 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:48.073 [Request Handler] Sending stream: 0 bytes of get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:48.073 [Request Handler] Ready to Stop: true
TRACE 2014-03-15 14:06:48.073 [Request Handler] Close connection
TRACE 2014-03-15 14:06:48.080 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.085 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49958,localport=5001]
TRACE 2014-03-15 14:06:48.085 [Request Handler] Received on socket: GET /get/0$8$2/Rundfunk+SWR4 HTTP/1.1
TRACE 2014-03-15 14:06:48.085 [Request Handler] Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
TRACE 2014-03-15 14:06:48.085 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.085 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:48.085 [Request Handler] Received on socket: Accept: */*
TRACE 2014-03-15 14:06:48.085 [Request Handler] Received on socket: Range: bytes=0-
TRACE 2014-03-15 14:06:48.085 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player"
TRACE 2014-03-15 14:06:48.085 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"
TRACE 2014-03-15 14:06:48.085 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2014-03-15 14:06:48.086 [Request Handler] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-15 14:06:48.086 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:48.086 [Request Handler] HTTP: get/0$8$2/Rundfunk+SWR4 / 0--1
TRACE 2014-03-15 14:06:48.086 [Request Handler] Wrote on socket: HTTP/1.1 206 Partial Content
TRACE 2014-03-15 14:06:48.086 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:48.086 [Request Handler] discover 0$8 refresh forced: false
TRACE 2014-03-15 14:06:48.086 [Request Handler] Wrote on socket: TransferMode.DLNA.ORG: Streaming
TRACE 2014-03-15 14:06:48.086 [Request Handler] Asked stream chunk : TimeRange [start=0.0, end=0.0] of Rundfunk SWR4 and player VLC Web Audio (Legacy)
TRACE 2014-03-15 14:06:48.086 [Request Handler] Ready to Stop: false
TRACE 2014-03-15 14:06:48.086 [Request Handler] Wrote on socket: Content-Type: audio/wav
TRACE 2014-03-15 14:06:48.086 [Request Handler] Using chunked response. Sending 1058732 bytes.
TRACE 2014-03-15 14:06:48.086 [Request Handler] Wrote on socket: Content-Range: bytes 0-1058731/*
TRACE 2014-03-15 14:06:48.086 [Request Handler] Wrote on socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2014-03-15 14:06:48.086 [Request Handler] Wrote on socket: Connection: keep-alive
TRACE 2014-03-15 14:06:48.086 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:48.086 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:48.105 [Request Handler] Sending stream with premature end: 0 bytes of get/0$8$2/Rundfunk+SWR4. Reason: Connection reset by peer
TRACE 2014-03-15 14:06:48.105 [Request Handler] Ready to Stop: true
TRACE 2014-03-15 14:06:48.108 [Request Handler] Sending stream: 0 bytes of get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:48.110 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.110 [Request Handler] Close connection
TRACE 2014-03-15 14:06:48.115 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49959,localport=5001]
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: GET /get/0$8$2/Rundfunk+SWR4 HTTP/1.1
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
TRACE 2014-03-15 14:06:48.115 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: Accept: */*
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: Range: bytes=0-511
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player"
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2014-03-15 14:06:48.115 [Request Handler] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-15 14:06:48.115 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:48.115 [Request Handler] HTTP: get/0$8$2/Rundfunk+SWR4 / 0-511
TRACE 2014-03-15 14:06:48.115 [Request Handler] Wrote on socket: HTTP/1.1 206 Partial Content
TRACE 2014-03-15 14:06:48.115 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:48.115 [Request Handler] discover 0$8 refresh forced: false
TRACE 2014-03-15 14:06:48.115 [Request Handler] Wrote on socket: TransferMode.DLNA.ORG: Streaming
TRACE 2014-03-15 14:06:48.115 [Request Handler] Asked stream chunk : ByteRange [start=0, end=511] of Rundfunk SWR4 and player VLC Web Audio (Legacy)
TRACE 2014-03-15 14:06:48.115 [Request Handler] Ready to Stop: false
TRACE 2014-03-15 14:06:48.115 [Request Handler] Wrote on socket: Content-Type: audio/wav
TRACE 2014-03-15 14:06:48.115 [Request Handler] Using chunked response. Sending 512 bytes.
TRACE 2014-03-15 14:06:48.116 [Request Handler] Wrote on socket: Content-Range: bytes 0-511/*
TRACE 2014-03-15 14:06:48.116 [Request Handler] Wrote on socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2014-03-15 14:06:48.116 [Request Handler] Wrote on socket: Connection: keep-alive
TRACE 2014-03-15 14:06:48.116 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:48.116 [Request Handler] Wrote on socket: Content-Length: 512
TRACE 2014-03-15 14:06:48.116 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:48.133 [Request Handler] Sending stream with premature end: 0 bytes of get/0$8$2/Rundfunk+SWR4. Reason: Connection reset by peer
TRACE 2014-03-15 14:06:48.133 [Request Handler] Ready to Stop: true
TRACE 2014-03-15 14:06:48.133 [Request Handler] Sending stream: 0 bytes of get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:48.142 [Request Handler] Close connection
TRACE 2014-03-15 14:06:48.143 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.149 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49960,localport=5001]
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: GET /get/0$8$2/Rundfunk+SWR4 HTTP/1.1
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
TRACE 2014-03-15 14:06:48.149 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: Accept: */*
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: Range: bytes=512-30531
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player"
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2014-03-15 14:06:48.149 [Request Handler] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-15 14:06:48.149 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:48.149 [Request Handler] HTTP: get/0$8$2/Rundfunk+SWR4 / 512-30531
TRACE 2014-03-15 14:06:48.149 [Request Handler] Wrote on socket: HTTP/1.1 206 Partial Content
TRACE 2014-03-15 14:06:48.149 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:48.149 [Request Handler] discover 0$8 refresh forced: false
TRACE 2014-03-15 14:06:48.149 [Request Handler] Wrote on socket: TransferMode.DLNA.ORG: Streaming
TRACE 2014-03-15 14:06:48.149 [Request Handler] Asked stream chunk : ByteRange [start=512, end=30531] of Rundfunk SWR4 and player VLC Web Audio (Legacy)
TRACE 2014-03-15 14:06:48.149 [Request Handler] Ready to Stop: false
DEBUG 2014-03-15 14:06:48.149 [Request Handler] Setting InputStream new position to: 512
TRACE 2014-03-15 14:06:48.149 [Request Handler] Wrote on socket: Content-Type: audio/wav
TRACE 2014-03-15 14:06:48.149 [Request Handler] Using chunked response. Sending 30020 bytes.
TRACE 2014-03-15 14:06:48.149 [Request Handler] Wrote on socket: Content-Range: bytes 512-30531/*
TRACE 2014-03-15 14:06:48.149 [Request Handler] Wrote on socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2014-03-15 14:06:48.149 [Request Handler] Wrote on socket: Connection: keep-alive
TRACE 2014-03-15 14:06:48.149 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:48.150 [Request Handler] Wrote on socket: Content-Length: 30020
TRACE 2014-03-15 14:06:48.150 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:48.224 [Request Handler] Sending stream with premature end: 98304 bytes of get/0$8$2/Rundfunk+SWR4. Reason: Connection reset by peer
TRACE 2014-03-15 14:06:48.224 [Request Handler] Ready to Stop: true
TRACE 2014-03-15 14:06:48.225 [Request Handler] Sending stream: 98304 bytes of get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:48.232 [Request Handler] Close connection
TRACE 2014-03-15 14:06:48.243 [HTTP Server] Receiving a request from: xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.244 [Request Handler] Opened request handler on socket Socket[addr=/xxx.xxx.xxx.16,port=49961,localport=5001]
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: GET /get/0$8$2/Rundfunk+SWR4 HTTP/1.1
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
TRACE 2014-03-15 14:06:48.244 [Request Handler] Matched media renderer "Sony Blu-ray" based on address /xxx.xxx.xxx.16
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: Host: xxx.xxx.xxx.6:5001
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: Accept: */*
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: Range: bytes=170-17396
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player"
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2014-03-15 14:06:48.244 [Request Handler] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-15 14:06:48.244 [Request Handler] Recognized media renderer: Sony Blu-ray
TRACE 2014-03-15 14:06:48.244 [Request Handler] HTTP: get/0$8$2/Rundfunk+SWR4 / 170-17396
TRACE 2014-03-15 14:06:48.244 [Request Handler] Wrote on socket: HTTP/1.1 206 Partial Content
TRACE 2014-03-15 14:06:48.244 [Request Handler] discover 0 refresh forced: false
TRACE 2014-03-15 14:06:48.244 [Request Handler] discover 0$8 refresh forced: false
TRACE 2014-03-15 14:06:48.244 [Request Handler] Wrote on socket: TransferMode.DLNA.ORG: Streaming
TRACE 2014-03-15 14:06:48.244 [Request Handler] Asked stream chunk : ByteRange [start=170, end=17396] of Rundfunk SWR4 and player VLC Web Audio (Legacy)
TRACE 2014-03-15 14:06:48.244 [Request Handler] Ready to Stop: false
DEBUG 2014-03-15 14:06:48.244 [Request Handler] Setting InputStream new position to: 170
TRACE 2014-03-15 14:06:48.244 [Request Handler] Wrote on socket: Content-Type: audio/wav
TRACE 2014-03-15 14:06:48.249 [Request Handler] Using chunked response. Sending 17227 bytes.
TRACE 2014-03-15 14:06:48.249 [Request Handler] Wrote on socket: Content-Range: bytes 170-17396/*
TRACE 2014-03-15 14:06:48.249 [Request Handler] Wrote on socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=LPCM;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2014-03-15 14:06:48.249 [Request Handler] Wrote on socket: Connection: keep-alive
TRACE 2014-03-15 14:06:48.249 [Request Handler] Wrote on socket: Server: Linux-i386-2.6.32-5-686, UPnP/1.0, UMS/3.4.2
TRACE 2014-03-15 14:06:48.249 [Request Handler] Wrote on socket: Content-Length: 17227
TRACE 2014-03-15 14:06:48.249 [Request Handler] Wrote on socket:
TRACE 2014-03-15 14:06:49.190 [vlc-74-Timer] buffered: 946,562 bytes / inputs: 1
TRACE 2014-03-15 14:06:51.190 [vlc-74-Timer] buffered: 1,029,506 bytes / inputs: 1
TRACE 2014-03-15 14:06:53.190 [vlc-74-Timer] buffered: 1,120,514 bytes / inputs: 1
TRACE 2014-03-15 14:06:53.388 [Request Handler] Sending stream with premature end: 131072 bytes of get/0$8$2/Rundfunk+SWR4. Reason: Connection reset by peer
TRACE 2014-03-15 14:06:53.388 [Request Handler] Ready to Stop: true
TRACE 2014-03-15 14:06:53.389 [Request Handler] Sending stream: 131072 bytes of get/0$8$2/Rundfunk+SWR4
TRACE 2014-03-15 14:06:53.397 [Request Handler] Close connection
TRACE 2014-03-15 14:06:55.191 [vlc-74-Timer] buffered: 1,374,380 bytes / inputs: 0
DEBUG 2014-03-15 14:06:55.893 [vlc-74-Cleanup] Stopping Unix process 27585: vlc-74
TRACE 2014-03-15 14:06:55.893 [vlc-74-Cleanup] Killing the Unix process: 27585
DEBUG 2014-03-15 14:06:55.893 [vlc-74-Cleanup] Stopping Unix process 27580: mkfifo-73
TRACE 2014-03-15 14:06:55.893 [vlc-74-Cleanup] Killing the Unix process: 27580
TRACE 2014-03-15 14:06:55.897 [vlc-74-Cleanup] Destroying buffer
TRACE 2014-03-15 14:06:55.901 [vlc-74] EOF
INFO 2014-03-15 14:06:57.397 [StopPlaying Event] Stopped playing Rundfunk SWR4 on your Sony Blu-ray
DEBUG 2014-03-15 14:06:57.398 [StopPlaying Event] The full filename of which is: http://swr.ic.llnwd.net/stream/swr_mp3_s_swr4rpa and the address of the renderer is: xxx.xxx.xxx.16
DEBUG 2014-03-15 14:06:57.398 [StopPlaying Event] add WebAudioStream [id=null, name=Rundfunk SWR4, full path=null, ext=WEB, discovered=false] to last played VirtualFolder [id=8, name=SWR4 Rheinland-Pfalz, full path=0$8, ext=null, discovered=true] RecentlyPlayed [id=1, name=Recently Played, full path=0$1, ext=null, discovered=false]